nixbot

1this derivation will be built:2  /nix/store/x9d37rcb1g1x0mimnzd3icgq6jm4v1gf-niks3-go-unit-tests.drv3building '/nix/store/x9d37rcb1g1x0mimnzd3icgq6jm4v1gf-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/x9d37rcb1g1x0mimnzd3icgq6jm4v1gf-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestResolveStorePath76niks3-go-unit-tests> === CONT  TestFileTokenMissing77niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken78niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall79niks3-go-unit-tests> === CONT  TestConvertHashToNix3280niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3281niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3282niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format83niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format84niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format85niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format86niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix3287niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess88niks3-go-unit-tests> === CONT  TestFileTokenEmpty89niks3-go-unit-tests> === CONT  TestDumpPathSingleFile90niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)91niks3-go-unit-tests> === CONT  TestParsePathInfoJSON92niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format93niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format94niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format95niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format96niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input97niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input98niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only99niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only100niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON101niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON102niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format103niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter enabled after throttle name=server-test rate=5104niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix105niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility106niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)107niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)108niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon109niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon110niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI111niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI112niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512113niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512114niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails115niks3-go-unit-tests> === CONT  TestEncodeNixBase32116niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash117niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash118niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input119niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input120niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash121niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)122niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand123niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)124niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh125niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)126niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport127niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)128niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches129niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)130niks3-go-unit-tests> === CONT  TestStaticToken131niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)132niks3-go-unit-tests> === CONT  TestSetClientTLSErrors133niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)134niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)135niks3-go-unit-tests> === CONT  TestRateLimiterFeedback136niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON137niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility138niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field139niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field140niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter141niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text142niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text143niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive144niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter145niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive146niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter147niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text148niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter149niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter150niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter151niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter152niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text153niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter154niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method155niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths156niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method157niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format158niks3-go-unit-tests> === CONT  TestGetStorePathHash159niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths160niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path161niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path162niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths163niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error164niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths165niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths166niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error167niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error168niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error169niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error170niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error171niks3-go-unit-tests> === CONT  TestDumpPathWriterError172niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only173niks3-go-unit-tests> === CONT  TestPartSizeForNAR174niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum175niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum176niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum177niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum178niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum179niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum180niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts181niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts182niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB183niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB184niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object185niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object186niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB187niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB188niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer189niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists190niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists191niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing192niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing193niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format194niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)195niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)196niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)197niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)198niks3-go-unit-tests> === CONT  TestCaseHackSuffix199niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file200niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file201niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file202niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file203niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file204niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file205niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file206niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file207niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input208niks3-go-unit-tests> === CONT  TestSetClientTLS209niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)210niks3-go-unit-tests> === CONT  TestShellSplitErrors211niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)212niks3-go-unit-tests> === CONT  TestShellSplit213niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)214niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody215niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter enabled after throttle name=server-test rate=5216niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51766217niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter backed off name=server-test rate=5218niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51766219niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)220niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format221niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)222niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)223niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)224niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)225niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)226niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)227niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON228niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert229niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert230niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA231niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA232niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport233niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport234niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)235niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI236niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512237niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon238niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)239niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)240niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)241niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)242niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)243niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash244niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input245niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)246niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)247niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)248niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter249niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)250niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field251niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method252niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text253niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter enabled after throttle name=server-test rate=5254niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:51769255niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive256niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text257niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)258niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)259niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)260niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)261niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)262niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)263niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter backed off name=server-test rate=5264niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter265niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter266niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter267niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths268niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path269niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error270niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths271niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)272niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)273niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)274niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error275niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error276niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)277niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)278niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)279niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)280niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)281niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum282niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB283niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB284niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object285niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum286niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts287niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum288niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)289niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)290niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)291niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)292niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)293niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)294niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)295niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)296niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists297niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter enabled after throttle name=server-test rate=5298niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:51775299niks3-go-unit-tests> 2026/06/28 12:18:38 WARN Rate limiter backed off name=server-test rate=5300niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)301niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)302niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)303niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)304niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)305niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing306niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file307niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file308niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)309niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)310niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)311niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file312niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file313niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert314niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA315niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)316niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)317niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)318niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)319niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)320niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport321niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)322niks3-go-unit-tests> 2026/06/28 12:18:38 http: TLS handshake error from 127.0.0.1:51782: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.02s)327niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.05s)328niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.05s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.05s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.07s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.06s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.09s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-51714-1222200143/postgres1352796399/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /nix/var/nix/builds/nix-51714-1222200143/postgres1352796399/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-51714-1222200143/postgres1352796399:5432 - no response364niks3-go-unit-tests> 2026-06-28 12:18:39.836 UTC [51750] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 12:18:39.837 UTC [51750] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-51714-1222200143/postgres1352796399/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 12:18:39.838 UTC [51754] LOG:  database system was shut down at 2026-06-28 12:18:39 UTC367niks3-go-unit-tests> 2026-06-28 12:18:39.840 UTC [51750] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-51714-1222200143/postgres1352796399:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T12:18:39.964026Z","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(9)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestClientCADerivations384niks3-go-unit-tests> === PAUSE TestClientCADerivations385niks3-go-unit-tests> === RUN   TestClientErrorHandling386niks3-go-unit-tests> === PAUSE TestClientErrorHandling387niks3-go-unit-tests> === RUN   TestClientIntegration388niks3-go-unit-tests> === PAUSE TestClientIntegration389niks3-go-unit-tests> === RUN   TestClientMultipleUploads390niks3-go-unit-tests> === PAUSE TestClientMultipleUploads391niks3-go-unit-tests> === RUN   TestClientWithDependencies392niks3-go-unit-tests> === PAUSE TestClientWithDependencies393niks3-go-unit-tests> === RUN   TestPinProtectsFromGC394niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC395niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences396niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences397niks3-go-unit-tests> === RUN   TestGCMetrics398niks3-go-unit-tests> === PAUSE TestGCMetrics399niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew400niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew401niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams402niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams403niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams404niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams405niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty406niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty407niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest408niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest409niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask410niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask411niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates412niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates413niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail414niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail415niks3-go-unit-tests> === RUN   TestService_healthCheckHandler416niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler417niks3-go-unit-tests> === RUN   TestGenerateLandingPage418niks3-go-unit-tests> === PAUSE TestGenerateLandingPage419niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug420niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug421niks3-go-unit-tests> === RUN   TestMetricsInventory422niks3-go-unit-tests> === PAUSE TestMetricsInventory423niks3-go-unit-tests> === RUN   TestService_NativeMTLS424niks3-go-unit-tests> === PAUSE TestService_NativeMTLS425niks3-go-unit-tests> === RUN   TestServerTLSConfig426niks3-go-unit-tests> === PAUSE TestServerTLSConfig427niks3-go-unit-tests> === RUN   TestMultipartCleanup428niks3-go-unit-tests> === PAUSE TestMultipartCleanup429niks3-go-unit-tests> === RUN   TestObjectStatsTrigger430niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger431niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC432niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC433niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest434niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest435niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted436niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted437niks3-go-unit-tests> === RUN   TestParseSingleRange438niks3-go-unit-tests> === PAUSE TestParseSingleRange439niks3-go-unit-tests> === RUN   TestIsValidCachePath440niks3-go-unit-tests> === PAUSE TestIsValidCachePath441niks3-go-unit-tests> === RUN   TestReadProxyNarinfo442niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo443niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed444niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed445niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming446niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming447niks3-go-unit-tests> === RUN   TestReadProxy404448niks3-go-unit-tests> === PAUSE TestReadProxy404449niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath450niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath451niks3-go-unit-tests> === RUN   TestReadProxyHead452niks3-go-unit-tests> === PAUSE TestReadProxyHead453niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet454niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet455niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML456niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML457niks3-go-unit-tests> === RUN   TestReadProxyDisabled458niks3-go-unit-tests> === PAUSE TestReadProxyDisabled459niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest460niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest461niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload462niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload463niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists464niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists465niks3-go-unit-tests> === RUN   TestService_Rustfstest466niks3-go-unit-tests> === PAUSE TestService_Rustfstest467niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle468niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle469niks3-go-unit-tests> === RUN   TestProxyWriteTimeout470niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout471niks3-go-unit-tests> === RUN   TestIsValidUploadKey472niks3-go-unit-tests> === PAUSE TestIsValidUploadKey473niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys474niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys475niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody476niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody477niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler478niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler479niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler480niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler481niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity482niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity483niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered484niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered485niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT486niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT487niks3-go-unit-tests> === CONT  TestService_AuthMiddleware488niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody489niks3-go-unit-tests> === CONT  TestService_NativeMTLS490niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT491niks3-go-unit-tests> === CONT  TestMetricsInventory492niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug493niks3-go-unit-tests> === CONT  TestGenerateLandingPage494niks3-go-unit-tests> === CONT  TestService_healthCheckHandler495niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail496niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)497niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates498niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)499niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask500niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)501niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest502niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)503niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty504niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)505niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams506niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)507niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams508niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)509niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew510niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)511niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity512niks3-go-unit-tests> === CONT  TestGCMetrics513niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)514niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered515niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure516niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure517niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart518niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart519niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts520niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts521niks3-go-unit-tests> === CONT  TestClientErrorHandling522niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath523niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath524niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken525niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken526niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable527niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable528niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences529niks3-go-unit-tests> 2026-06-28 12:18:40.353 UTC [51823] ERROR:  relation "goose_db_version" does not exist at character 36530niks3-go-unit-tests> 2026-06-28 12:18:40.353 UTC [51823] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC531niks3-go-unit-tests> 2026-06-28 12:18:40.386 UTC [51824] ERROR:  relation "goose_db_version" does not exist at character 36532niks3-go-unit-tests> 2026-06-28 12:18:40.386 UTC [51824] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC533niks3-go-unit-tests> 2026-06-28 12:18:40.419 UTC [51827] ERROR:  relation "goose_db_version" does not exist at character 36534niks3-go-unit-tests> 2026-06-28 12:18:40.419 UTC [51827] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC535niks3-go-unit-tests> 2026-06-28 12:18:40.419 UTC [51825] ERROR:  relation "goose_db_version" does not exist at character 36536niks3-go-unit-tests> 2026-06-28 12:18:40.419 UTC [51825] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC537niks3-go-unit-tests> 2026-06-28 12:18:40.420 UTC [51826] ERROR:  relation "goose_db_version" does not exist at character 36538niks3-go-unit-tests> 2026-06-28 12:18:40.420 UTC [51826] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC539niks3-go-unit-tests> 2026-06-28 12:18:40.422 UTC [51828] ERROR:  relation "goose_db_version" does not exist at character 36540niks3-go-unit-tests> 2026-06-28 12:18:40.422 UTC [51828] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC541niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (67.13ms)542niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (1.36ms)543niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (5.15ms)544niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (24.73ms)545niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (1.96ms)546niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (2.91ms)547niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000548niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (11.34ms)549niks3-go-unit-tests> 2026-06-28 12:18:40.438 UTC [51829] ERROR:  relation "goose_db_version" does not exist at character 36550niks3-go-unit-tests> 2026-06-28 12:18:40.438 UTC [51829] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC551niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (2.06ms)552niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (11.47ms)553niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (898.25µs)554niks3-go-unit-tests> 2026-06-28 12:18:40.439 UTC [51830] ERROR:  relation "goose_db_version" does not exist at character 36555niks3-go-unit-tests> 2026-06-28 12:18:40.439 UTC [51830] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC556niks3-go-unit-tests> 2026-06-28 12:18:40.441 UTC [51831] ERROR:  relation "goose_db_version" does not exist at character 36557niks3-go-unit-tests> 2026-06-28 12:18:40.441 UTC [51831] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC558niks3-go-unit-tests> 2026-06-28 12:18:40.441 UTC [51832] ERROR:  relation "goose_db_version" does not exist at character 36559niks3-go-unit-tests> 2026-06-28 12:18:40.441 UTC [51832] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC560niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (5.85ms)561niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (3.89ms)562niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (15.77ms)563niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (4.41ms)564niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000565niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (14.17ms)566niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (4.34ms)567niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (465.25µs)568niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2569niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (887µs)570niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (814.71µs)571niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.61ms)572niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000573niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.66ms)574niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (2.4ms)575niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.83ms)576niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (322µs)577niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2578niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.4ms)579niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.9ms)580niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (552.88µs)581niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2582niks3-go-unit-tests> 2026/06/28 12:18:40 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"583niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.39s)584niks3-go-unit-tests> === CONT  TestPinProtectsFromGC585niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (2.28ms)586niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000587niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.96ms)588niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000589niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (2.32ms)590niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000591niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.39s)592niks3-go-unit-tests> === CONT  TestClientWithDependencies593niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.65ms)594niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (2.95ms)595niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.94ms)596niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (7.08ms)597niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (1.13ms)598niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2599niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (7.18ms)600niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (433.58µs)601niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2602niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (457.17µs)603niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2604niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (7.74ms)605niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (8.46ms)606niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (861.83µs)607niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (520.29µs)608niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (750.29µs)609niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (863.54µs)610niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures611niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures612niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete multipart upload request method=POST path=/api/multipart/complete613niks3-go-unit-tests> 2026/06/28 12:18:40 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst614niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.39s)615niks3-go-unit-tests> === CONT  TestClientMultipleUploads616niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (2.02ms)617niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (2.19ms)618niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.99ms)619niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (3.43ms)620niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.72ms)621niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000622niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.76ms)623niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000624niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.97ms)625niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000626niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.40s)627niks3-go-unit-tests> === CONT  TestClientIntegration628niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.79ms)629niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.49ms)630niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000631niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.52ms)632niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.6ms)633niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (665.54µs)634niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2635niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (743.08µs)636niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2637niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (682.17µs)638niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2639niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.32ms)640niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.41s)641niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath642niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (1.09ms)643niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2644niks3-go-unit-tests> 2026/06/28 12:18:40 WARN mTLS auth: subject not in bound subjects subject="CN=reader"645niks3-go-unit-tests> 2026/06/28 12:18:40 WARN mTLS auth: subject not in bound subjects subject="CN=writer"646niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.41s)647niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys648niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info649niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info650niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal651niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal652niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key653niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key654niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key655niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key656niks3-go-unit-tests> === CONT  TestIsValidUploadKey657niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo658niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo659niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst660niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst661niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz662niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz663niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain664niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain665niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing666niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing667niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log668niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log669niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file670niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file671niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name672niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name673niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark674niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark675niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals676niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals677niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation678niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation679niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output680niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output681niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info682niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info683niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html684niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html685niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type686niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type687niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type688niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type689niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type690niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type691niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Aborted multipart uploads count=0692niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal693niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal694niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar695niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar696niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute697niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute698niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key699niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key700niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type701niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type702niks3-go-unit-tests> === CONT  TestProxyWriteTimeout703niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo704niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo705niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar706niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar707niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar708niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar709niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size710niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size711niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle712niks3-go-unit-tests> {"timestamp":"2026-06-28T12:18:40.464245Z","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(10)"}713niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Created nix-cache-info in bucket bucket=bucket7714niks3-go-unit-tests> 2026/06/28 12:18:40 WARN Force mode enabled - objects will be deleted immediately without grace period715niks3-go-unit-tests> 2026/06/28 12:18:40 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=0716niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Vacuumed table table=pending_closures717niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Vacuumed table table=pending_objects718niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Vacuumed table table=multipart_uploads719niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Vacuumed table table=closures720niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Vacuumed table table=objects721niks3-go-unit-tests> --- PASS: TestGCMetrics (0.41s)722niks3-go-unit-tests> === CONT  TestService_Rustfstest723niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug724niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-51714-1222200143/TestNARDeduplicationMetadataUploadBug1686124188/001/store/945jxjjs49wjqsbk1yl0xxvxij8kyvwn-file1.txt725niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete multipart upload request method=POST path=/api/multipart/complete726niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=M2IwYmMwNDctYzlmNy00NjhiLWJmZDAtNzZhMmI1YjY1MGRiLjdkOTQyNTM4LTljY2QtNGU5Mi1hNTczLTU2YTE5Njk3NjMzMngxNzgyNjQ5MTIwNDU3NjEwMDAw parts=10727niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete728niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed upload id=1729niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures730niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures731niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo732niks3-go-unit-tests> 2026/06/28 12:18:40 WARN Found objects in DB but missing from S3, will re-upload count=1733niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.55s)734niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists735niks3-go-unit-tests> 2026-06-28 12:18:40.624 UTC [51854] ERROR:  relation "goose_db_version" does not exist at character 36736niks3-go-unit-tests> 2026-06-28 12:18:40.624 UTC [51854] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC737niks3-go-unit-tests> 2026-06-28 12:18:40.624 UTC [51855] ERROR:  relation "goose_db_version" does not exist at character 36738niks3-go-unit-tests> 2026-06-28 12:18:40.624 UTC [51855] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC739niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (7.69ms)740niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (474.42µs)741niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (8.8ms)742niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (436.5µs)743niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (660µs)744niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (4.43ms)745niks3-go-unit-tests> 2026-06-28 12:18:40.652 UTC [51858] ERROR:  relation "goose_db_version" does not exist at character 36746niks3-go-unit-tests> 2026-06-28 12:18:40.652 UTC [51858] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC747niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (5.82ms)748niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000749niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (924.75µs)750niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (285.63µs)751niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2752niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Created nix-cache-info in bucket bucket=bucket11753niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (17.9ms)754niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000755niks3-go-unit-tests> 2026-06-28 12:18:40.668 UTC [51861] ERROR:  relation "goose_db_version" does not exist at character 36756niks3-go-unit-tests> 2026-06-28 12:18:40.668 UTC [51861] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC757niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (4.06ms)758niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (648.25µs)759niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2760niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures761niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Created nix-cache-info in bucket bucket=bucket12762niks3-go-unit-tests> 2026-06-28 12:18:40.674 UTC [51862] ERROR:  relation "goose_db_version" does not exist at character 36763niks3-go-unit-tests> 2026-06-28 12:18:40.674 UTC [51862] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC764niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)765niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 945jxjjs49wjqsbk1yl0xxvxij8kyvwn-file1.txt (160B)766niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (18.6ms)767niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (748.17µs)768niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign769niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Signed narinfos id=1 count=1770niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 narinfos771niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.02ms)772niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete773niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (1.33ms)774niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000775niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.60s)776niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload777niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.59ms)778niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (1.11ms)779niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2780niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Created nix-cache-info in bucket bucket=bucket13781niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed upload id=1782niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Upload complete. (104ms)783niks3-go-unit-tests> 2026-06-28 12:18:40.695 UTC [51868] ERROR:  relation "goose_db_version" does not exist at character 36784niks3-go-unit-tests> 2026-06-28 12:18:40.695 UTC [51868] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC785niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug786niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:787niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-51714-1222200143/TestNARDeduplicationMetadataUploadBug1686124188/001/store/945jxjjs49wjqsbk1yl0xxvxij8kyvwn-file1.txt788niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst789niks3-go-unit-tests>         Compression: zstd790niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf791niks3-go-unit-tests>         NarSize: 160792niks3-go-unit-tests>         References: 793niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf794niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (15.3ms)795niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)796niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):797niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}798niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (1.91ms)799niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (17.5ms)800niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (6.15ms)801niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (7.84ms)802niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (7.37ms)803niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (21.71ms)804niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000805niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (2.92ms)806niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (19.83ms)807niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000808niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (919.08µs)809niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2810niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (14.16ms)811niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (347.71µs)812niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2813niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-51714-1222200143/TestNARDeduplicationMetadataUploadBug1686124188/001/store/bw7b19v66h289q2jr9qc27aza10xasvn-file2.txt814niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.29s)815niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest816niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Created nix-cache-info in bucket bucket=bucket15817niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (51.85ms)818niks3-go-unit-tests> === NAME  TestClientIntegration819niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-51714-1222200143/TestClientIntegration3315586135/002/store/1djgwzdkcvd0746ssvz0hvy8kx879zb1-test-file.txt820niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (918.13µs)821niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.44ms)822niks3-go-unit-tests> === NAME  TestPinProtectsFromGC823niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-51714-1222200143/TestPinProtectsFromGC329735134/001/store/05da73h3bk57ibvp2abib0vlp4gssgr9-pinned-file.txt824niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-51714-1222200143/TestPinProtectsFromGC329735134/001/store/2z7nizj9c4gmqk1rbaigf7nqiibqy29d-unpinned-file.txt825niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (11.19ms)826niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000827niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.58ms)828niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (481.96µs)829niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2830niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures831niks3-go-unit-tests> === NAME  TestClientMultipleUploads832niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-51714-1222200143/TestClientMultipleUploads2453685964/001/store/1za495br8dxlcsmd2r1f7kll6z6acwg4-test-file-0.txt833niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete multipart upload request method=POST path=/api/multipart/complete834niks3-go-unit-tests> 2026-06-28 12:18:40.829 UTC [51915] ERROR:  relation "goose_db_version" does not exist at character 36835niks3-go-unit-tests> 2026-06-28 12:18:40.829 UTC [51915] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC836niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (7.35ms)837niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (754.46µs)838niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (1.55ms)839niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (5.37ms)840niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000841niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (2.14ms)842niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (422.17µs)843niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2844niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.39s)845niks3-go-unit-tests> === CONT  TestReadProxyDisabled846niks3-go-unit-tests> === NAME  TestClientMultipleUploads847niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-51714-1222200143/TestClientMultipleUploads2453685964/001/store/g9326q2bj2s8az3w2pjdhh22y8n77jlz-test-file-1.txt848niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures849niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)850niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign851niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Signed narinfos id=2 count=1852niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 narinfos853niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete854niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed upload id=2855niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Upload complete. (86ms)856niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug857niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:858niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-51714-1222200143/TestNARDeduplicationMetadataUploadBug1686124188/001/store/bw7b19v66h289q2jr9qc27aza10xasvn-file2.txt859niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst860niks3-go-unit-tests>         Compression: zstd861niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf862niks3-go-unit-tests>         NarSize: 160863niks3-go-unit-tests>         References: 864niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf865niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)866niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):867niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}868niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.82s)869niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML870niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures871niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures872niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)873niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 05da73h3bk57ibvp2abib0vlp4gssgr9-pinned-file.txt (128B)874niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign875niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Signed narinfos id=1 count=1876niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 narinfos877niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete878niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed upload id=1879niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Upload complete. (87ms)880niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)881niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1djgwzdkcvd0746ssvz0hvy8kx879zb1-test-file.txt (152B)882niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign883niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Signed narinfos id=1 count=1884niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Uploading 1 narinfos885niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete886niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed upload id=1887niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Upload complete. (98ms)888niks3-go-unit-tests> === NAME  TestClientIntegration889niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:890niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-51714-1222200143/TestClientIntegration3315586135/002/store/1djgwzdkcvd0746ssvz0hvy8kx879zb1-test-file.txt891niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst892niks3-go-unit-tests>         Compression: zstd893niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1894niks3-go-unit-tests>         NarSize: 152895niks3-go-unit-tests>         References: 896niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1897niks3-go-unit-tests> === NAME  TestClientMultipleUploads898niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-51714-1222200143/TestClientMultipleUploads2453685964/001/store/7abi8xkr5w68dmfkm7s0k0nn3fv0iq4s-test-file-2.txt899niks3-go-unit-tests> === NAME  TestClientIntegration900niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)901niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):902niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}903niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...904niks3-go-unit-tests> === NAME  TestClientWithDependencies905niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-51714-1222200143/TestClientWithDependencies1332522820/001/store/axdj33gi1ywjv4n41q146zi0ragra7i8-test-script906niks3-go-unit-tests> 2026-06-28 12:18:40.935 UTC [51938] ERROR:  relation "goose_db_version" does not exist at character 36907niks3-go-unit-tests> 2026-06-28 12:18:40.935 UTC [51938] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC908niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Starting cleanup of old closures method=DELETE path=/api/closures909niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Garbage collection started910niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Aborted multipart uploads count=0911niks3-go-unit-tests> 2026/06/28 12:18:40 WARN Force mode enabled - objects will be deleted immediately without grace period912niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20241026095416_initial_model.sql (9.4ms)913niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251210153512_drop_unused_gin_index.sql (477.71µs)914niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20251218171726_add_pins.sql (808.25µs)915niks3-go-unit-tests> 2026/06/28 12:18:40 OK   20260628120000_add_object_size_and_stats.sql (3.64ms)916niks3-go-unit-tests> 2026/06/28 12:18:40 goose: successfully migrated database to version: 20260628120000917niks3-go-unit-tests> 2026/06/28 12:18:40 OK   1_commit_pending_closure.sql (1.39ms)918niks3-go-unit-tests> 2026/06/28 12:18:40 OK   2_object_stats_trigger.sql (691.17µs)919niks3-go-unit-tests> 2026/06/28 12:18:40 goose: up to current file version: 2920niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received uploads request method=POST path=/api/pending_closures921niks3-go-unit-tests> 2026-06-28 12:18:40.967 UTC [51942] ERROR:  relation "goose_db_version" does not exist at character 36922niks3-go-unit-tests> 2026-06-28 12:18:40.967 UTC [51942] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC923niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)924niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Received complete multipart upload request method=POST path=/api/multipart/complete925niks3-go-unit-tests> {"timestamp":"2026-06-28T12:18:40.988728Z","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(3)"}926niks3-go-unit-tests> {"timestamp":"2026-06-28T12:18:40.988761Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket18, 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(3)"}927niks3-go-unit-tests> 2026/06/28 12:18:40 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=M2IwYmMwNDctYzlmNy00NjhiLWJmZDAtNzZhMmI1YjY1MGRiLjczZGUwNTkxLTgzNzQtNDBkMC05YTY1LWE5ZWRlODA0OGI1OHgxNzgyNjQ5MTIwOTgwMDQ3MDAw928niks3-go-unit-tests> 2026/06/28 12:18:40 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=M2IwYmMwNDctYzlmNy00NjhiLWJmZDAtNzZhMmI1YjY1MGRiLjczZGUwNTkxLTgzNzQtNDBkMC05YTY1LWE5ZWRlODA0OGI1OHgxNzgyNjQ5MTIwOTgwMDQ3MDAw parts=1929niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.38s)930niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet931niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures932niks3-go-unit-tests> 2026-06-28 12:18:41.008 UTC [51953] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-28 12:18:41.008 UTC [51953] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (26.65ms)935niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)936niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 2z7nizj9c4gmqk1rbaigf7nqiibqy29d-unpinned-file.txt (128B)937niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (545.58µs)938niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (792.5µs)939niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign940niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=2 count=1941niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 narinfos942niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete943niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=2944niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Upload complete. (79ms)945niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures946niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (10.38ms)947niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 20260628120000948niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.15ms)949niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (434.63µs)950niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 2951niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures952niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures953niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures954niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)955niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 7abi8xkr5w68dmfkm7s0k0nn3fv0iq4s-test-file-2.txt (160B)956niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1za495br8dxlcsmd2r1f7kll6z6acwg4-test-file-0.txt (160B)957niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading g9326q2bj2s8az3w2pjdhh22y8n77jlz-test-file-1.txt (160B)958niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign959niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=1 count=1960niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign961niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=2 count=1962niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign963niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=3 count=1964niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 3 narinfos965niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures966niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete967niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (10.92ms)968niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (945.08µs)969niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=3970niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete971niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=1972niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete973niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (1.51ms)974niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=2975niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Upload complete. (99ms)976niks3-go-unit-tests> === NAME  TestClientMultipleUploads977niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 133.72875ms978niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (2.6ms)979niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 20260628120000980niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures981niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.46ms)982niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (369.17µs)983niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 2984niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)985niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading axdj33gi1ywjv4n41q146zi0ragra7i8-test-script (136B)986niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.59s)987niks3-go-unit-tests> === CONT  TestReadProxyHead988niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign989niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=1 count=1990niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 narinfos991niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete992niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received create pin request method=POST path=/api/pins/myapp993niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.30s)994niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects995niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-51714-1222200143/TestPinProtectsFromGC329735134/001/store/05da73h3bk57ibvp2abib0vlp4gssgr9-pinned-file.txt narinfo_key=05da73h3bk57ibvp2abib0vlp4gssgr9.narinfo996niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=1997niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Upload complete. (49ms)998niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures999niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Garbage collection started1000niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Aborted multipart uploads count=01001niks3-go-unit-tests> === NAME  TestClientWithDependencies1002niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-51714-1222200143/TestClientWithDependencies1332522820/001/store) requires matching store prefix1003niks3-go-unit-tests> 2026/06/28 12:18:41 WARN Force mode enabled - objects will be deleted immediately without grace period1004niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.61s)1005niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1006niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1007niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=M2IwYmMwNDctYzlmNy00NjhiLWJmZDAtNzZhMmI1YjY1MGRiLjllMjkzZGU2LTA2ZWItNDFkZi1iNDA0LTlhNTM4NGVmMjY2Y3gxNzgyNjQ5MTIxMDI3MDIxMDAw parts=121008niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.47s)1009niks3-go-unit-tests> === CONT  TestClientCADerivations1010niks3-go-unit-tests> 2026-06-28 12:18:41.166 UTC [51966] ERROR:  relation "goose_db_version" does not exist at character 361011niks3-go-unit-tests> 2026-06-28 12:18:41.166 UTC [51966] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1012niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (21.65ms)1013niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (1.63ms)1014niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (725.88µs)1015niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (8.22ms)1016niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001017niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.32ms)1018niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (365.67µs)1019niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21020niks3-go-unit-tests> 2026-06-28 12:18:41.210 UTC [51967] ERROR:  relation "goose_db_version" does not exist at character 361021niks3-go-unit-tests> 2026-06-28 12:18:41.210 UTC [51967] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1022niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.35s)1023niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler1024niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (10.65ms)1025niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (717.5µs)1026niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (1.77ms)1027niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (1.89ms)1028niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001029niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.72ms)1030niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (259.92µs)1031niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21032niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.36s)1033niks3-go-unit-tests> === CONT  TestCacheConfigHandler1034niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1035niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1036niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1037niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1038niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1039niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1040niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1041niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1042niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1043niks3-go-unit-tests> 2026/06/28 12:18:41 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=01044niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_closures1045niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_objects1046niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=multipart_uploads1047niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=closures1048niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=objects1049niks3-go-unit-tests> 2026-06-28 12:18:41.316 UTC [51973] ERROR:  relation "goose_db_version" does not exist at character 361050niks3-go-unit-tests> 2026-06-28 12:18:41.316 UTC [51973] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1051niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (7.65ms)1052niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (428.04µs)1053niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (3.32ms)1054niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (4.02ms)1055niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001056niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.04ms)1057niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (198.67µs)1058niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21059niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.35s)1060niks3-go-unit-tests> === CONT  TestParseSingleRange1061niks3-go-unit-tests> === RUN   TestParseSingleRange/none1062niks3-go-unit-tests> === PAUSE TestParseSingleRange/none1063niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit1064niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit1065niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored1066niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored1067niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash1068niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash1069niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty1070niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty1071niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start1072niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start1073niks3-go-unit-tests> === RUN   TestParseSingleRange/closed1074niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed1075niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended1076niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended1077niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size1078niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size1079niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix1080niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix1081niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size1082niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size1083niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte1084niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte1085niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF1086niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF1087niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF1088niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF1089niks3-go-unit-tests> === CONT  TestReadProxy4041090niks3-go-unit-tests> 2026/06/28 12:18:41 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=01091niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_closures1092niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_objects1093niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=multipart_uploads1094niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=closures1095niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=objects1096niks3-go-unit-tests> 2026-06-28 12:18:41.387 UTC [51976] ERROR:  relation "goose_db_version" does not exist at character 361097niks3-go-unit-tests> 2026-06-28 12:18:41.387 UTC [51976] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1098niks3-go-unit-tests> 2026-06-28 12:18:41.414 UTC [51977] ERROR:  relation "goose_db_version" does not exist at character 361099niks3-go-unit-tests> 2026-06-28 12:18:41.414 UTC [51977] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1100niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (21.14ms)1101niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (613.92µs)1102niks3-go-unit-tests> 2026-06-28 12:18:41.418 UTC [51978] ERROR:  relation "goose_db_version" does not exist at character 361103niks3-go-unit-tests> 2026-06-28 12:18:41.418 UTC [51978] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1104niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (2.3ms)1105niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (7.96ms)1106niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001107niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (7.14ms)1108niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (212.79µs)1109niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21110niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.39s)1111niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming1112niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (16.92ms)1113niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (419.96µs)1114niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (663.42µs)1115niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (3.19ms)1116niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001117niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (8.67ms)1118niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (451.21µs)1119niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (958.5µs)1120niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (349.29µs)1121niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21122niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (1.65ms)1123niks3-go-unit-tests> 2026/06/28 12:18:41 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1124niks3-go-unit-tests> 2026/06/28 12:18:41 WARN mTLS auth: bound subjects configured but subject DN unavailable1125niks3-go-unit-tests> 2026/06/28 12:18:41 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1126niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.40s)1127niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed1128niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (3.25ms)1129niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001130niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (814.08µs)1131niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (238.54µs)1132niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21133niks3-go-unit-tests> 2026/06/28 12:18:41 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1134niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.40s)1135niks3-go-unit-tests> === CONT  TestReadProxyNarinfo1136niks3-go-unit-tests> 2026-06-28 12:18:41.477 UTC [51985] ERROR:  relation "goose_db_version" does not exist at character 361137niks3-go-unit-tests> 2026-06-28 12:18:41.477 UTC [51985] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1138niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (11.04ms)1139niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (463.83µs)1140niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (994.54µs)1141niks3-go-unit-tests> 2026-06-28 12:18:41.505 UTC [51986] ERROR:  relation "goose_db_version" does not exist at character 361142niks3-go-unit-tests> 2026-06-28 12:18:41.505 UTC [51986] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1143niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (14ms)1144niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001145niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (887.75µs)1146niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (252.21µs)1147niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21148niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Created nix-cache-info in bucket bucket=bucket271149niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (6.1ms)1150niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (384.75µs)1151niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (628.33µs)1152niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (4.35ms)1153niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001154niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.09ms)1155niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (322.17µs)1156niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21157niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1158niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1159niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1160niks3-go-unit-tests> 2026-06-28 12:18:41.596 UTC [51990] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-28 12:18:41.596 UTC [51990] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (11.04ms)1163niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (514.75µs)1164niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (726.13µs)1165niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (3.15ms)1166niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001167niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.21ms)1168niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (439.13µs)1169niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21170niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.38s)1171niks3-go-unit-tests> === CONT  TestIsValidCachePath1172niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo1173niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo1174niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars1175niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1176niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst1177niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst1178niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz1179niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz1180niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz21181niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz21182niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed1183niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed1184niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls1185niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls1186niks3-go-unit-tests> === RUN   TestIsValidCachePath/log1187niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log1188niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation1189niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation1190niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info1191niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info1192niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html1193niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html1194niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent1195niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent1196niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle1197niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle1198niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e1199niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e1200niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u1201niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u1202niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path1203niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path1204niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty1205niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty1206niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash1207niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash1208niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension1209niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension1210niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash1211niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash1212niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler1213niks3-go-unit-tests> 2026-06-28 12:18:41.662 UTC [51995] ERROR:  relation "goose_db_version" does not exist at character 361214niks3-go-unit-tests> 2026-06-28 12:18:41.662 UTC [51995] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1215niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1216niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=M2IwYmMwNDctYzlmNy00NjhiLWJmZDAtNzZhMmI1YjY1MGRiLjQxZWMxNDcxLTExYjctNDE2ZS1hODY2LWE4YWI5MGNmMWRhZHgxNzgyNjQ5MTIxNTczOTcwMDAw parts=101217niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1218niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=11219niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001220niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (12.12ms)1221niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1222niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (343.13µs)1223niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures1224niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Aborted multipart uploads count=01225niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (647.29µs)1226niks3-go-unit-tests> 2026/06/28 12:18:41 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=01227niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (2.83ms)1228niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001229niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_closures1230niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.27ms)1231niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=pending_objects1232niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (321µs)1233niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21234niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=multipart_uploads1235niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.35s)1236niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC1237niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=closures1238niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Vacuumed table table=objects1239niks3-go-unit-tests> === NAME  TestClientCADerivations1240niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-51714-1222200143/TestClientCADerivations3979222423/001/store/mzx9s1ii4209zv8zjhc8srmgr2g5fqxc-ca-test1241niks3-go-unit-tests> 2026-06-28 12:18:41.739 UTC [51999] ERROR:  relation "goose_db_version" does not exist at character 361242niks3-go-unit-tests> 2026-06-28 12:18:41.739 UTC [51999] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1243niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001244niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.53s)1245niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted1246niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (5.06ms)1247niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (692.42µs)1248niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (1.08ms)1249niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (2.2ms)1250niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001251niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.2ms)1252niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (277.17µs)1253niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21254niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.32s)1255niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest1256niks3-go-unit-tests> 2026-06-28 12:18:41.763 UTC [52004] ERROR:  relation "goose_db_version" does not exist at character 361257niks3-go-unit-tests> 2026-06-28 12:18:41.763 UTC [52004] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1258niks3-go-unit-tests> === NAME  TestClientCADerivations1259niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1260niks3-go-unit-tests> 2026-06-28 12:18:41.783 UTC [52007] ERROR:  relation "goose_db_version" does not exist at character 361261niks3-go-unit-tests> 2026-06-28 12:18:41.783 UTC [52007] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1262niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (23.27ms)1263niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (388.46µs)1264niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (792.38µs)1265niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (1.86ms)1266niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001267niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.13ms)1268niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (483.96µs)1269niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21270niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.36s)1271niks3-go-unit-tests> === CONT  TestMultipartCleanup1272niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (6.91ms)1273niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (738.38µs)1274niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (735.88µs)1275niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (2.47ms)1276niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001277niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (945.42µs)1278niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (356.92µs)1279niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21280niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.36s)1281niks3-go-unit-tests> === CONT  TestObjectStatsTrigger1282niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1283niks3-go-unit-tests> 2026-06-28 12:18:41.872 UTC [52017] ERROR:  relation "goose_db_version" does not exist at character 361284niks3-go-unit-tests> 2026-06-28 12:18:41.872 UTC [52017] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1285niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1286niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading mzx9s1ii4209zv8zjhc8srmgr2g5fqxc-ca-test (144B)1287niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1288niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Signed narinfos id=1 count=11289niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Uploading 1 narinfos1290niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1291niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Completed upload id=11292niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Upload complete. (91ms)1293niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (5.95ms)1294niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (400.58µs)1295niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (669.5µs)1296niks3-go-unit-tests> === NAME  TestClientCADerivations1297niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-51714-1222200143/TestClientCADerivations3979222423/001/store/mzx9s1ii4209zv8zjhc8srmgr2g5fqxc-ca-test1298niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1299niks3-go-unit-tests>         Compression: zstd1300niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1301niks3-go-unit-tests>         NarSize: 1441302niks3-go-unit-tests>         References: 1303niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-51714-1222200143/TestClientCADerivations3979222423/001/store/rlr3py3sp5z7fhym3f6279dzc3af6dch-ca-test.drv1304niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1305niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1306niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1307niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1308niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (4.08ms)1309niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001310niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.01ms)1311niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (497.71µs)1312niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21313niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received cleanup request method=DELETE path=/api/pending_closures1314niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Aborted multipart uploads count=01315niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/api/pending_closures1316niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received cleanup request method=DELETE path=/api/pending_closures1317niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Aborted multipart uploads count=11318niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1319niks3-go-unit-tests> 2026-06-28 12:18:41.905 UTC [52017] ERROR:  Closure does not exist: id=11320niks3-go-unit-tests> 2026-06-28 12:18:41.905 UTC [52017] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1321niks3-go-unit-tests> 2026-06-28 12:18:41.905 UTC [52017] STATEMENT:  -- name: CommitPendingClosure :exec1322niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1323niks3-go-unit-tests> 	1324niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.29s)1325niks3-go-unit-tests> === CONT  TestServerTLSConfig1326niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA1327niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA1328niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file1329niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file1330niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file1331niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file1332niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1333niks3-go-unit-tests> 2026/06/28 12:18:41 INFO OIDC provider initialized name=test1334niks3-go-unit-tests> === NAME  TestClientCADerivations1335niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket27?endpoint=http://localhost:51785&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-51714-1222200143/TestClientCADerivations3979222423/001/store'1336niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11337niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.77s)1338niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1339niks3-go-unit-tests> 2026/06/28 12:18:41 INFO Received uploads request method=POST path=/1340niks3-go-unit-tests> 2026-06-28 12:18:41.930 UTC [52022] ERROR:  relation "goose_db_version" does not exist at character 361341niks3-go-unit-tests> 2026-06-28 12:18:41.930 UTC [52022] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1342niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (5.52ms)1343niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (456.83µs)1344niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (679µs)1345niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (3.97ms)1346niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001347niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.12ms)1348niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (201.33µs)1349niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21350niks3-go-unit-tests> 2026-06-28 12:18:41.972 UTC [52023] ERROR:  relation "goose_db_version" does not exist at character 361351niks3-go-unit-tests> 2026-06-28 12:18:41.972 UTC [52023] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1352niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20241026095416_initial_model.sql (5.67ms)1353niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251210153512_drop_unused_gin_index.sql (361.58µs)1354niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20251218171726_add_pins.sql (645.04µs)1355niks3-go-unit-tests> 2026/06/28 12:18:41 OK   20260628120000_add_object_size_and_stats.sql (2.95ms)1356niks3-go-unit-tests> 2026/06/28 12:18:41 goose: successfully migrated database to version: 202606281200001357niks3-go-unit-tests> 2026/06/28 12:18:41 OK   1_commit_pending_closure.sql (1.01ms)1358niks3-go-unit-tests> 2026/06/28 12:18:41 OK   2_object_stats_trigger.sql (242.54µs)1359niks3-go-unit-tests> 2026/06/28 12:18:41 goose: up to current file version: 21360niks3-go-unit-tests> 2026-06-28 12:18:42.000 UTC [52024] ERROR:  relation "goose_db_version" does not exist at character 361361niks3-go-unit-tests> 2026-06-28 12:18:42.000 UTC [52024] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1362niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (7.65ms)1363niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (413.58µs)1364niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (645.25µs)1365niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.29s)1366niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1367niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (13.58ms)1368niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001369niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (973.5µs)1370niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (260.96µs)1371niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21372niks3-go-unit-tests> 2026-06-28 12:18:42.046 UTC [52027] ERROR:  relation "goose_db_version" does not exist at character 361373niks3-go-unit-tests> 2026-06-28 12:18:42.046 UTC [52027] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1374niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (31.73ms)1375niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (420.21µs)1376niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (756.67µs)1377niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (1.37ms)1378niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001379niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (844.79µs)1380niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (186.71µs)1381niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21382niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received uploads request method=POST path=/api/pending_closures1383niks3-go-unit-tests> 2026-06-28 12:18:42.097 UTC [52028] ERROR:  relation "goose_db_version" does not exist at character 361384niks3-go-unit-tests> 2026-06-28 12:18:42.097 UTC [52028] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1385niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (3.88ms)1386niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (378.67µs)1387niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (697.79µs)1388niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (1.05ms)1389niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001390niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (852.54µs)1391niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (177.33µs)1392niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21393niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.31s)1394niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1395niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received request for more parts method=POST path=/1396niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1397niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received complete multipart upload request method=POST path=/1398niks3-go-unit-tests> 2026-06-28 12:18:42.153 UTC [52029] ERROR:  relation "goose_db_version" does not exist at character 361399niks3-go-unit-tests> 2026-06-28 12:18:42.153 UTC [52029] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1400niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1401niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (3.25ms)1402niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (538.17µs)1403niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (919.25µs)1404niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (1.37ms)1405niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001406niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (842.25µs)1407niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (208.79µs)1408niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21409niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1410niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1411niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1412niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1413niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1414niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1415niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1416niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1417niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1418niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1419niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1420niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1421niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1422niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1423niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1424niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1425niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.48s)1426niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1427niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received uploads request method=POST path=/1428niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1429niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received complete multipart upload request method=POST path=/1430niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1431niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received request for more parts method=POST path=/1432niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1433niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received uploads request method=POST path=/1434niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1435niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1436niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1437niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1438niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1439niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1440niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1441niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1442niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1443niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1444niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1445niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1446niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1447niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1448niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1449niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1450niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1451niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1452niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1453niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1454niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1455niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1456niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1457niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1458niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1459niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1460niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1461niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1462niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1463niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1464niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1465niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1466niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1467niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1468niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1469niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1470niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1471niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1472niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1473niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1474niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1475niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1476niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1477niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1478niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1479niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1480niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1481niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1482niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1483niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1484niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1485niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1486niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1487niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1488niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1489niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1490niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1491niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1492niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1493niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1494niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1495niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1496niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1497niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1498niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1499niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1500niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1501niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1502niks3-go-unit-tests> === CONT  TestParseSingleRange/none1503niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1504niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1505niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1506niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1507niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1508niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1509niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1510niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1511niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1512niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1513niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1514niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1515niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1516niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1517niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1518niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1519niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1520niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1521niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1522niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1523niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1524niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1525niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1526niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1527niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1528niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1529niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1530niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1531niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1532niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1533niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1534niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1535niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1536niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1537niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1538niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1539niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1540niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1541niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1542niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1543niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1544niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1545niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1546niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1547niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1548niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21549niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1550niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1551niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1552niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1553niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1554niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1555niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1556niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1557niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1558niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1559niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1560niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1561niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1562niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1563niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1564niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1565niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1566niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1567niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1568niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1569niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1570niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1571niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1572niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1573niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1574niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1575niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1576niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1577niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1578niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1579niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1580niks3-go-unit-tests> 2026/06/28 12:18:42 INFO OIDC auth successful provider=test1581niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1582niks3-go-unit-tests> 2026/06/28 12:18:42 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]1583niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1584niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1585niks3-go-unit-tests> 2026/06/28 12:18:42 WARN Authentication failed token_preview=eyJhbGciOi...g4J5eW7Uqg 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]1586niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.27s)1587niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1588niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1589niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1590niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1591niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.03s)1592niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1593niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1594niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.27s)1595niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Received cleanup request method=DELETE path=/api/pending_closures1596niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Aborted multipart uploads count=11597niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.42s)1598niks3-go-unit-tests> 2026-06-28 12:18:42.244 UTC [52035] ERROR:  relation "goose_db_version" does not exist at character 361599niks3-go-unit-tests> 2026-06-28 12:18:42.244 UTC [52035] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1600niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (3.85ms)1601niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (358.75µs)1602niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (779.38µs)1603niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (947.46µs)1604niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001605niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (3.39ms)1606niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (237.33µs)1607niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21608niks3-go-unit-tests> 2026-06-28 12:18:42.303 UTC [52042] ERROR:  relation "goose_db_version" does not exist at character 361609niks3-go-unit-tests> 2026-06-28 12:18:42.303 UTC [52042] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1610niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20241026095416_initial_model.sql (4.88ms)1611niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251210153512_drop_unused_gin_index.sql (444.71µs)1612niks3-go-unit-tests> 2026/06/28 12:18:42 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1613niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20251218171726_add_pins.sql (676.75µs)1614niks3-go-unit-tests> 2026/06/28 12:18:42 OK   20260628120000_add_object_size_and_stats.sql (1.12ms)1615niks3-go-unit-tests> 2026/06/28 12:18:42 goose: successfully migrated database to version: 202606281200001616niks3-go-unit-tests> 2026/06/28 12:18:42 OK   1_commit_pending_closure.sql (908µs)1617niks3-go-unit-tests> 2026/06/28 12:18:42 OK   2_object_stats_trigger.sql (202.75µs)1618niks3-go-unit-tests> 2026/06/28 12:18:42 goose: up to current file version: 21619niks3-go-unit-tests> 2026/06/28 12:18:42 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=194.604739ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1620niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1621niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1622niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1623niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1624niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201625niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101626niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101627niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (0.72s)1628niks3-go-unit-tests> 2026/06/28 12:18:42 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1629niks3-go-unit-tests> 2026/06/28 12:18:42 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=420.161396ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1630niks3-go-unit-tests> 2026/06/28 12:18:42 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01631niks3-go-unit-tests> === NAME  TestClientIntegration1632niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1633niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1634niks3-go-unit-tests> --- PASS: TestClientIntegration (2.50s)1635niks3-go-unit-tests> 2026/06/28 12:18:43 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=874.540583ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1636niks3-go-unit-tests> 2026/06/28 12:18:43 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01637niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1638niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1639niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.62s)1640niks3-go-unit-tests> 2026/06/28 12:18:43 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.519200592s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1641niks3-go-unit-tests> 2026/06/28 12:18:44 WARN Rate limiter enabled after throttle name=s3-test rate=51642niks3-go-unit-tests> 2026/06/28 12:18:44 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1643niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1644niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101645niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001646niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.33s)1647niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1648niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.26s)1649niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.31s)1650niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.29s)1651niks3-go-unit-tests> PASS1652niks3-go-unit-tests> 2026-06-28 12:18:46.016 UTC [51750] LOG:  received smart shutdown request1653niks3-go-unit-tests> 2026-06-28 12:18:46.017 UTC [51750] LOG:  background worker "logical replication launcher" (PID 51757) exited with exit code 11654niks3-go-unit-tests> 2026-06-28 12:18:46.022 UTC [51752] LOG:  shutting down1655niks3-go-unit-tests> 2026-06-28 12:18:46.022 UTC [51752] LOG:  checkpoint starting: shutdown immediate1656niks3-go-unit-tests> 2026-06-28 12:18:47.001 UTC [51752] LOG:  checkpoint complete: wrote 13433 buffers (82.0%); 0 WAL file(s) added, 0 removed, 11 recycled; write=0.723 s, sync=0.232 s, total=0.980 s; sync files=13767, longest=0.001 s, average=0.001 s; distance=188552 kB, estimate=188552 kB; lsn=0/CD34E18, redo lsn=0/CD34E181657niks3-go-unit-tests> 2026-06-28 12:18:47.005 UTC [51750] LOG:  database system is shut down1658niks3-go-unit-tests> Running OIDC tests...1659niks3-go-unit-tests> === RUN   TestGlobMatch1660niks3-go-unit-tests> === PAUSE TestGlobMatch1661niks3-go-unit-tests> === RUN   TestAudienceForIssuer1662niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1663niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1664niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1665niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1666niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1667niks3-go-unit-tests> === RUN   TestValidateToken_Expired1668niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1669niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1670niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1671niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1672niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1673niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1674niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1675niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1676niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1677niks3-go-unit-tests> === CONT  TestGlobMatch1678niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1679niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1680niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1681niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1682niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1683niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1684niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1685niks3-go-unit-tests> === RUN   TestGlobMatch/*_1686niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1687niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1688niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1689niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1690niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1691niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1692niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1693niks3-go-unit-tests> === CONT  TestValidateToken_Expired1694niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1695niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1696niks3-go-unit-tests> === CONT  TestAudienceForIssuer1697niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1698niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1699niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1700niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1701niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1702niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1703niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1704niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1705niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1706niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1707niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1708niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1709niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1710niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1711niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1712niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1713niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1714niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1715niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1716niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1717niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1718niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1719niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01720niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01721niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1722niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1723niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1724niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1725niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1726niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1727niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1728niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1729niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1730niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1731niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1732niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1733niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1734niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1735niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1736niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1737niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1738niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1739niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1740niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1741niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1742niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1743niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1744niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1745niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1746niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01747niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1748niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1749niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1750niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1751niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1752niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1753niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1754niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1755niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1756niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1757niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1758niks3-go-unit-tests> === CONT  TestGlobMatch/*_1759niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1760niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1761niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1762niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1763niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1764niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1765niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1766niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1767niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1768niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1769niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1770niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1771niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1772niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1773niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1774niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1775niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1776niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1777niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1778niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1779niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1780niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1781niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1782niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1783niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1784niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1785niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1786niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1787niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1788niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=test1789niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=provider11790niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=test1791niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=test1792niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=test1793niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=test1794niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=provider11795niks3-go-unit-tests> 2026/06/28 12:18:47 INFO OIDC provider initialized name=provider21796niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1797niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1798niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1799niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1800niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1801niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1802niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1803niks3-go-unit-tests> PASS1804niks3-go-unit-tests> Running hook tests...1805niks3-go-unit-tests> === RUN   TestSendPathsEmpty1806niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1807niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1808niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1809niks3-go-unit-tests> === RUN   TestQueueDeduplication1810niks3-go-unit-tests> === PAUSE TestQueueDeduplication1811niks3-go-unit-tests> === RUN   TestQueueRemove1812niks3-go-unit-tests> === PAUSE TestQueueRemove1813niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1814niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1815niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1816niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1817niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1818niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1819niks3-go-unit-tests> === RUN   TestServerClientIntegration1820niks3-go-unit-tests> === PAUSE TestServerClientIntegration1821niks3-go-unit-tests> === RUN   TestServerQueueError1822niks3-go-unit-tests> === PAUSE TestServerQueueError1823niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1824niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1825niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1826niks3-go-unit-tests>         PASS1827niks3-go-unit-tests>         1828niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1829niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1830niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1831niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1832niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1833niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1834niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1835niks3-go-unit-tests> === CONT  TestSendPathsEmpty1836niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1837niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1838niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1839niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1840niks3-go-unit-tests> === CONT  TestServerQueueError1841niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1842niks3-go-unit-tests> === CONT  TestQueueRemove1843niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1844niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1845niks3-go-unit-tests> === CONT  TestQueueDeduplication1846niks3-go-unit-tests> === CONT  TestServerClientIntegration1847niks3-go-unit-tests> 2026/06/28 12:18:48 ERROR Failed to queue paths error="permission denied" count=11848niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1849niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1850niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1851niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Upload queue status pending=21852niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Uploading batch count=21853niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Upload queue status pending=21854niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Uploading batch count=11855niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1856niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1857niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1858niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1859niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Upload queue status pending=21860niks3-go-unit-tests> 2026/06/28 12:18:48 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-51714-1222200143/TestWorkerSkipsGCdPaths3872279952/002/nonexistent1861niks3-go-unit-tests> 2026/06/28 12:18:48 INFO Uploading batch count=11862niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1863niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1864niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1865niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1866niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.12s)1867niks3-go-unit-tests> PASS18681869post-build step Upload coverage to codecov: ok1870Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests