nixbot

1this derivation will be built:2  /nix/store/c97xcrlv3xln1ihxpyns6vmk0kn4pp4h-niks3-go-unit-tests.drv3building '/nix/store/c97xcrlv3xln1ihxpyns6vmk0kn4pp4h-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/c97xcrlv3xln1ihxpyns6vmk0kn4pp4h-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestFileTokenMissing76niks3-go-unit-tests> === CONT  TestResolveStorePath77niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport78niks3-go-unit-tests> === CONT  TestStaticToken79niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)80niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches81niks3-go-unit-tests> === CONT  TestConvertHashToNix3282niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3283niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3284niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format85niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format86niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format87niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format88niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix3289niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess90niks3-go-unit-tests> === CONT  TestSetClientTLSErrors91niks3-go-unit-tests> === CONT  TestEncodeNixBase3292niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash93niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash94niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input95niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input96niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash97niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash98niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)99niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand100niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)101niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails102niks3-go-unit-tests> === CONT  TestDumpPathSingleFile103niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter enabled after throttle name=server-test rate=5104niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)105niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input106niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)107niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)108niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)109niks3-go-unit-tests> === CONT  TestDumpPathWriterError110niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix111niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)112niks3-go-unit-tests> === CONT  TestShellSplitErrors113niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)114niks3-go-unit-tests> === CONT  TestSetClientTLS115niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)116niks3-go-unit-tests> === CONT  TestPartSizeForNAR117niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum118niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum119niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum120niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum121niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum122niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum123niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts124niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file125niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)126niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer127niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists128niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts129niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB130niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB131niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object132niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object133niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB134niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB135niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists136niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility137niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing138niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)139niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file140niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing141niks3-go-unit-tests> === CONT  TestRateLimiterFeedback142niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file143niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)144niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon145niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file146niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon147niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file148niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI149niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file150niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file151niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI152niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512153niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512154niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter155niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter156niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file157niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter158niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter159niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter160niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter161niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter162niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter163niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths164niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths165niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths166niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility167niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field168niks3-go-unit-tests> === CONT  TestParsePathInfoJSON169niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format170niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths171niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths172niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field173niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text174niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text175niks3-go-unit-tests> === CONT  TestCaseHackSuffix176niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive177niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format178niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format179niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format180niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive181niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text182niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text183niks3-go-unit-tests> === CONT  TestShellSplit184niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method185niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody186niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)187niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input188niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input189niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only190niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)191niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method192niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only193niks3-go-unit-tests> === CONT  TestGetStorePathHash194niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path195niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path196niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error197niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error198niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error199niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON200niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error201niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error202niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error203niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON204niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format205niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh206niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format207niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)208niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)209niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)210niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)211niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON212niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter enabled after throttle name=server-test rate=5213niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52608214niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter backed off name=server-test rate=5215niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52608216niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)217niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken218niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert219niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert220niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA221niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA222niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport223niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport224niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall225niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)226niks3-go-unit-tests> === CONT  TestFileTokenEmpty227niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)228niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum229niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists230niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing231niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)232niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)233niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)234niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB235niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object236niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB237niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts238niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum239niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum240niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)241niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)242niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)243niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)244niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)245niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)246niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)247niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)248niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)249niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512250niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI251niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon252niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)253niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)254niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)255niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)256niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)257niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file258niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file259niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file260niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file261niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter262niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter enabled after throttle name=server-test rate=5263niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:52615264niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter backed off name=server-test rate=5265niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter266niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)267niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)268niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)269niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)270niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)271niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter272niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter273niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter enabled after throttle name=server-test rate=5274niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:52621275niks3-go-unit-tests> 2026/06/28 14:39:14 WARN Rate limiter backed off name=server-test rate=5276niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)277niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)278niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)279niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)280niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)281niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths282niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths283niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)284niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)285niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)286niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field287niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text288niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive289niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text290niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method291niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)292niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)293niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)294niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)295niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)296niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)297niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path298niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format299niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only300niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON301niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error302niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error303niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input304niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format305niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)306niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)307niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)308niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)309niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)310niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)311niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error312niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)313niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)314niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)315niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)316niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)317niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert318niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)319niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport320niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)321niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA322niks3-go-unit-tests> 2026/06/28 14:39:14 http: TLS handshake error from 127.0.0.1:52623: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)328niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.04s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.05s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.06s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.08s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.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-70157-115164581/postgres2623235233/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-70157-115164581/postgres2623235233/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-70157-115164581/postgres2623235233:5432 - no response364niks3-go-unit-tests> 2026-06-28 14:39:16.379 UTC [70192] 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 14:39:16.379 UTC [70192] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-70157-115164581/postgres2623235233/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 14:39:16.380 UTC [70196] LOG:  database system was shut down at 2026-06-28 14:39:16 UTC367niks3-go-unit-tests> 2026-06-28 14:39:16.382 UTC [70192] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-70157-115164581/postgres2623235233:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:16.503348Z","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(2)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestCacheStatsHandler384niks3-go-unit-tests> === PAUSE TestCacheStatsHandler385niks3-go-unit-tests> === RUN   TestClientCADerivations386niks3-go-unit-tests> === PAUSE TestClientCADerivations387niks3-go-unit-tests> === RUN   TestClientErrorHandling388niks3-go-unit-tests> === PAUSE TestClientErrorHandling389niks3-go-unit-tests> === RUN   TestClientIntegration390niks3-go-unit-tests> === PAUSE TestClientIntegration391niks3-go-unit-tests> === RUN   TestClientMultipleUploads392niks3-go-unit-tests> === PAUSE TestClientMultipleUploads393niks3-go-unit-tests> === RUN   TestClientWithDependencies394niks3-go-unit-tests> === PAUSE TestClientWithDependencies395niks3-go-unit-tests> === RUN   TestPinProtectsFromGC396niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC397niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun398niks3-go-unit-tests> 2026-06-28 14:39:16.634 UTC [70247] ERROR:  relation "goose_db_version" does not exist at character 36399niks3-go-unit-tests> 2026-06-28 14:39:16.634 UTC [70247] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC400niks3-go-unit-tests> 2026/06/28 14:39:16 OK   20241026095416_initial_model.sql (3.01ms)401niks3-go-unit-tests> 2026/06/28 14:39:16 OK   20251210153512_drop_unused_gin_index.sql (486µs)402niks3-go-unit-tests> 2026/06/28 14:39:16 OK   20251218171726_add_pins.sql (722.58µs)403niks3-go-unit-tests> 2026/06/28 14:39:16 OK   20260628120000_add_object_size_and_stats.sql (751.63µs)404niks3-go-unit-tests> 2026/06/28 14:39:16 goose: successfully migrated database to version: 20260628120000405niks3-go-unit-tests> 2026/06/28 14:39:16 OK   1_commit_pending_closure.sql (862.75µs)406niks3-go-unit-tests> 2026/06/28 14:39:16 OK   2_object_stats_trigger.sql (206.88µs)407niks3-go-unit-tests> 2026/06/28 14:39:16 goose: up to current file version: 2408niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.05s)409niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences410niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences411niks3-go-unit-tests> === RUN   TestGCMetrics412niks3-go-unit-tests> === PAUSE TestGCMetrics413niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew414niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew415niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams416niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams417niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams418niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams419niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty420niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty421niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest422niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest423niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask424niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask425niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates426niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates427niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail428niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail429niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight430niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight431niks3-go-unit-tests> === RUN   TestService_healthCheckHandler432niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler433niks3-go-unit-tests> === RUN   TestGenerateLandingPage434niks3-go-unit-tests> === PAUSE TestGenerateLandingPage435niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug436niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug437niks3-go-unit-tests> === RUN   TestMetricsInventory438niks3-go-unit-tests> === PAUSE TestMetricsInventory439niks3-go-unit-tests> === RUN   TestService_NativeMTLS440niks3-go-unit-tests> === PAUSE TestService_NativeMTLS441niks3-go-unit-tests> === RUN   TestServerTLSConfig442niks3-go-unit-tests> === PAUSE TestServerTLSConfig443niks3-go-unit-tests> === RUN   TestMultipartCleanup444niks3-go-unit-tests> === PAUSE TestMultipartCleanup445niks3-go-unit-tests> === RUN   TestObjectStatsTrigger446niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger447niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC448niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC449niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest450niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest451niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted452niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted453niks3-go-unit-tests> === RUN   TestParseSingleRange454niks3-go-unit-tests> === PAUSE TestParseSingleRange455niks3-go-unit-tests> === RUN   TestIsValidCachePath456niks3-go-unit-tests> === PAUSE TestIsValidCachePath457niks3-go-unit-tests> === RUN   TestReadProxyNarinfo458niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo459niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed460niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed461niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming462niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming463niks3-go-unit-tests> === RUN   TestReadProxy404464niks3-go-unit-tests> === PAUSE TestReadProxy404465niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath466niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath467niks3-go-unit-tests> === RUN   TestReadProxyHead468niks3-go-unit-tests> === PAUSE TestReadProxyHead469niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet470niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet471niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML472niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML473niks3-go-unit-tests> === RUN   TestReadProxyDisabled474niks3-go-unit-tests> === PAUSE TestReadProxyDisabled475niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest476niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest477niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload478niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload479niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists480niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists481niks3-go-unit-tests> === RUN   TestService_Rustfstest482niks3-go-unit-tests> === PAUSE TestService_Rustfstest483niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated484niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)485niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy486niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)487niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy488niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"489niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"490niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"491niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/28 14:39:16 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)499niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle500niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle501niks3-go-unit-tests> === RUN   TestProxyWriteTimeout502niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout503niks3-go-unit-tests> === RUN   TestIsValidUploadKey504niks3-go-unit-tests> === PAUSE TestIsValidUploadKey505niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys506niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys507niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody508niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody509niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler510niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler511niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler512niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler513niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity514niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity515niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered516niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered517niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT518niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT519niks3-go-unit-tests> === CONT  TestService_AuthMiddleware520niks3-go-unit-tests> === CONT  TestMultipartCleanup521niks3-go-unit-tests> === CONT  TestReadProxyDisabled522niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed523niks3-go-unit-tests> === CONT  TestReadProxyHead524niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT525niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered526niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity527niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler528niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler529niks3-go-unit-tests> 2026-06-28 14:39:17.208 UTC [70270] ERROR:  relation "goose_db_version" does not exist at character 36530niks3-go-unit-tests> 2026-06-28 14:39:17.208 UTC [70270] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC531niks3-go-unit-tests> 2026-06-28 14:39:17.208 UTC [70269] ERROR:  relation "goose_db_version" does not exist at character 36532niks3-go-unit-tests> 2026-06-28 14:39:17.208 UTC [70269] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC533niks3-go-unit-tests> 2026-06-28 14:39:17.211 UTC [70271] ERROR:  relation "goose_db_version" does not exist at character 36534niks3-go-unit-tests> 2026-06-28 14:39:17.211 UTC [70271] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC535niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70272] ERROR:  relation "goose_db_version" does not exist at character 36536niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70272] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC537niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70273] ERROR:  relation "goose_db_version" does not exist at character 36538niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70273] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC539niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70274] ERROR:  relation "goose_db_version" does not exist at character 36540niks3-go-unit-tests> 2026-06-28 14:39:17.212 UTC [70274] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC541niks3-go-unit-tests> 2026-06-28 14:39:17.213 UTC [70276] ERROR:  relation "goose_db_version" does not exist at character 36542niks3-go-unit-tests> 2026-06-28 14:39:17.213 UTC [70276] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC543niks3-go-unit-tests> 2026-06-28 14:39:17.213 UTC [70275] ERROR:  relation "goose_db_version" does not exist at character 36544niks3-go-unit-tests> 2026-06-28 14:39:17.213 UTC [70275] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC545niks3-go-unit-tests> 2026-06-28 14:39:17.214 UTC [70277] ERROR:  relation "goose_db_version" does not exist at character 36546niks3-go-unit-tests> 2026-06-28 14:39:17.214 UTC [70277] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC547niks3-go-unit-tests> 2026-06-28 14:39:17.214 UTC [70278] ERROR:  relation "goose_db_version" does not exist at character 36548niks3-go-unit-tests> 2026-06-28 14:39:17.214 UTC [70278] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC549niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (6.89ms)550niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (6.61ms)551niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (978.88µs)552niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (639µs)553niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (6.27ms)554niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (2.7ms)555niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (2.12ms)556niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (982.96µs)557niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (7.94ms)558niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (7.12ms)559niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (8.21ms)560niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (631.13µs)561niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.65ms)562niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000563niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.73ms)564niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000565niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (728.33µs)566niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.6ms)567niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (843.29µs)568niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (7.28ms)569niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (8.14ms)570niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (585.29µs)571niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.35ms)572niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (6.87ms)573niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.58ms)574niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.63ms)575niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.75ms)576niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (620.63µs)577niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.67ms)578niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000579niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (518.96µs)580niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2581niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.77ms)582niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (363.75µs)583niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2584niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (617.04µs)585niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.14ms)586niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (8.88ms)587niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.02ms)588niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.1ms)589niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.42ms)590niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000591niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.19ms)592niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (488.96µs)593niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2594niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (956.21µs)595niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.96ms)596niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000597niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.09ms)598niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000599niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.7ms)600niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000601niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.02ms)602niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000603niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.41ms)604niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (1.7ms)605niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000606niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete607niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (934.63µs)608niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (437.29µs)609niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2610niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.36s)611niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML612niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.37ms)613niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.68ms)614niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.69ms)615niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (837.63µs)616niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2617niks3-go-unit-tests> 2026/06/28 14:39:17 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst618niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (2.06ms)619niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.36s)620niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody621niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.65ms)622niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (872.71µs)623niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2624niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (714.21µs)625niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2626niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (1.2ms)627niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2628niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (911.88µs)629niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2630niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.19ms)631niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000632niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures633niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:17.23303Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(2)"}634niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures635niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (2.01ms)636niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (5.47ms)637niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2638niks3-go-unit-tests> 2026/06/28 14:39:17 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"639niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.37s)640niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys641niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info642niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info643niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal644niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal645niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key646niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key647niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key648niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key649niks3-go-unit-tests> === CONT  TestService_Rustfstest650niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures651niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received cleanup request method=DELETE path=/api/pending_closures652niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures653niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.37s)654niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists655niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures656niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures657niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Aborted multipart uploads count=0658niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures659niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.38s)660niks3-go-unit-tests> === CONT  TestIsValidUploadKey661niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo662niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo663niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst664niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst665niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz666niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz667niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain668niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain669niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing670niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing671niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log672niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log673niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file674niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file675niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name676niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name677niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark678niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark679niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals680niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals681niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation682niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation683niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output684niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output685niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info686niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info687niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html688niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html689niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type690niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type691niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type692niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type693niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type694niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type695niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal696niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal697niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar698niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar699niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute700niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute701niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key702niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key703niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type704niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type705niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload706niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.38s)707niks3-go-unit-tests> === CONT  TestProxyWriteTimeout708niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo709niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo710niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar711niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar712niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar713niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar714niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size715niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size716niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle717niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received cleanup request method=DELETE path=/api/pending_closures718niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Aborted multipart uploads count=1719niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts720niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts721niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure722niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure723niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart724niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart725niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest726niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete727niks3-go-unit-tests> 2026-06-28 14:39:17.258 UTC [70276] ERROR:  Closure does not exist: id=1728niks3-go-unit-tests> 2026-06-28 14:39:17.258 UTC [70276] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE729niks3-go-unit-tests> 2026-06-28 14:39:17.258 UTC [70276] STATEMENT:  -- name: CommitPendingClosure :exec730niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)731niks3-go-unit-tests> 	732niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.38s)733niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted734niks3-go-unit-tests> 2026-06-28 14:39:17.295 UTC [70314] ERROR:  relation "goose_db_version" does not exist at character 36735niks3-go-unit-tests> 2026-06-28 14:39:17.295 UTC [70314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC736niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (13.24ms)737niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (2.12ms)738niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (11.89ms)739niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received cleanup request method=DELETE path=/api/pending_closures740niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Aborted multipart uploads count=1741niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.48s)742niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC743niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (18.5ms)744niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000745niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.31ms)746niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (309.67µs)747niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2748niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.13s)749niks3-go-unit-tests> === CONT  TestReadProxyNarinfo750niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete751niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete752niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=MTE3ZmI3ODQtMjZiYi00NGExLTg3YzYtNzQ1YThiMDBkMTdjLmRjOWRkYWY0LTI3MDMtNDZmNC1hNzEwLTE4MjIwY2E4ODdiM3gxNzgyNjU3NTU3MjQ3ODk2MDAw parts=10753niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete754niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=MTE3ZmI3ODQtMjZiYi00NGExLTg3YzYtNzQ1YThiMDBkMTdjLjRjYmVlYjE4LWQ0NDYtNDk5Zi1iOWUzLWMyMjA3MDlmNDdiZXgxNzgyNjU3NTU3MjQwNTM3MDAw parts=10755niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete756niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed upload id=1757niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000758niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed upload id=1759niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures760niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures761niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures762niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Starting cleanup of old closures method=DELETE path=/api/closures763niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo764niks3-go-unit-tests> 2026/06/28 14:39:17 WARN Found objects in DB but missing from S3, will re-upload count=1765niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.51s)766niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest767niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Aborted multipart uploads count=0768niks3-go-unit-tests> 2026-06-28 14:39:17.385 UTC [70321] ERROR:  relation "goose_db_version" does not exist at character 36769niks3-go-unit-tests> 2026-06-28 14:39:17.385 UTC [70321] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC770niks3-go-unit-tests> 2026/06/28 14:39:17 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=0771niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Vacuumed table table=pending_closures772niks3-go-unit-tests> 2026-06-28 14:39:17.398 UTC [70323] ERROR:  relation "goose_db_version" does not exist at character 36773niks3-go-unit-tests> 2026-06-28 14:39:17.398 UTC [70323] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC774niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Vacuumed table table=pending_objects775niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Vacuumed table table=multipart_uploads776niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Vacuumed table table=closures777niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (11.69ms)778niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Vacuumed table table=objects779niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (4.34ms)780niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (12.08ms)781niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.76ms)782niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000783niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (22.74ms)784niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.29ms)785niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (427.08µs)786niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (301.88µs)787niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2788niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000789niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.56s)790niks3-go-unit-tests> === CONT  TestIsValidCachePath791niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo792niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo793niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars794niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars795niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst796niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst797niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz798niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz799niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2800niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2801niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed802niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed803niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls804niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls805niks3-go-unit-tests> === RUN   TestIsValidCachePath/log806niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log807niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation808niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation809niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info810niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info811niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html812niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html813niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent814niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent815niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle816niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle817niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e818niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e819niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u820niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u821niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path822niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path823niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty824niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty825niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash826niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash827niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension828niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension829niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash830niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash831niks3-go-unit-tests> === CONT  TestParseSingleRange832niks3-go-unit-tests> === RUN   TestParseSingleRange/none833niks3-go-unit-tests> === PAUSE TestParseSingleRange/none834niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit835niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit836niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored837niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored838niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash839niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash840niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty841niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty842niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start843niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start844niks3-go-unit-tests> === RUN   TestParseSingleRange/closed845niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed846niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended847niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended848niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size849niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size850niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix851niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix852niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size853niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size854niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte855niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte856niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF857niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF858niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF859niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF860niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet861niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.75ms)862niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures863niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (5.77ms)864niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000865niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.11ms)866niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (538.13µs)867niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2868niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.20s)869niks3-go-unit-tests> === CONT  TestObjectStatsTrigger870niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete871niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:17.447128Z","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(11)"}872niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:17.447145Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket13, 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(11)"}873niks3-go-unit-tests> 2026/06/28 14:39:17 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=MTE3ZmI3ODQtMjZiYi00NGExLTg3YzYtNzQ1YThiMDBkMTdjLjVmY2UxOTI3LTNlNzgtNGFkYS04NTYxLWEwMzQzY2MwZGNhY3gxNzgyNjU3NTU3NDM5MjEyMDAw874niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MTE3ZmI3ODQtMjZiYi00NGExLTg3YzYtNzQ1YThiMDBkMTdjLjVmY2UxOTI3LTNlNzgtNGFkYS04NTYxLWEwMzQzY2MwZGNhY3gxNzgyNjU3NTU3NDM5MjEyMDAw parts=1875niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.20s)876niks3-go-unit-tests> === CONT  TestReadProxy404877niks3-go-unit-tests> 2026-06-28 14:39:17.487 UTC [70330] ERROR:  relation "goose_db_version" does not exist at character 36878niks3-go-unit-tests> 2026-06-28 14:39:17.487 UTC [70330] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC879niks3-go-unit-tests> 2026-06-28 14:39:17.517 UTC [70332] ERROR:  relation "goose_db_version" does not exist at character 36880niks3-go-unit-tests> 2026-06-28 14:39:17.517 UTC [70332] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC881niks3-go-unit-tests> 2026-06-28 14:39:17.517 UTC [70331] ERROR:  relation "goose_db_version" does not exist at character 36882niks3-go-unit-tests> 2026-06-28 14:39:17.517 UTC [70331] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC883niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (18.1ms)884niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (376.33µs)885niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (713.08µs)886niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (8.54ms)887niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000888niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.84ms)889niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (402.63µs)890niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2891niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures892niks3-go-unit-tests> 2026-06-28 14:39:17.537 UTC [70333] ERROR:  relation "goose_db_version" does not exist at character 36893niks3-go-unit-tests> 2026-06-28 14:39:17.537 UTC [70333] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC894niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (13.68ms)895niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (412.63µs)896niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (3.08ms)897niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures898niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.65ms)899niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000900niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (17.93ms)901niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (696µs)902niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.79ms)903niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (624.04µs)904niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2905niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.44ms)906niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.30s)907niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming908niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (6.22ms)909niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000910niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.14ms)911niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (334.25µs)912niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2913niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (12.86ms)914niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (424.29µs)915niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received uploads request method=POST path=/api/pending_closures916niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.96ms)917niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (6.39ms)918niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000919niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.19ms)920niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (365.38µs)921niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2922niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete923niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.37s)924niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath925niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Received complete multipart upload request method=POST path=/api/multipart/complete926niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=MTE3ZmI3ODQtMjZiYi00NGExLTg3YzYtNzQ1YThiMDBkMTdjLjU2ZGMzY2VmLTFiZmQtNDhhYi04NzdiLWViNDA0ZDdlMmU2OHgxNzgyNjU3NTU3NTQzMDIwMDAw parts=12927niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.43s)928niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew929niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)930niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight931niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Starting HTTP server address=127.0.0.1:52671932niks3-go-unit-tests> 2026/06/28 14:39:17 INFO Shutdown signal received, draining in-flight requests timeout=10s933niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.07s)934niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail935niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)936niks3-go-unit-tests> === CONT  TestServerTLSConfig937niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA938niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA939niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file940niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file941niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file942niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file943niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates944niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)945niks3-go-unit-tests> === CONT  TestService_NativeMTLS946niks3-go-unit-tests> 2026-06-28 14:39:17.872 UTC [70340] ERROR:  relation "goose_db_version" does not exist at character 36947niks3-go-unit-tests> 2026-06-28 14:39:17.872 UTC [70340] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC948niks3-go-unit-tests> 2026-06-28 14:39:17.874 UTC [70341] ERROR:  relation "goose_db_version" does not exist at character 36949niks3-go-unit-tests> 2026-06-28 14:39:17.874 UTC [70341] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC950niks3-go-unit-tests> 2026-06-28 14:39:17.875 UTC [70342] ERROR:  relation "goose_db_version" does not exist at character 36951niks3-go-unit-tests> 2026-06-28 14:39:17.875 UTC [70342] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC952niks3-go-unit-tests> 2026-06-28 14:39:17.900 UTC [70343] ERROR:  relation "goose_db_version" does not exist at character 36953niks3-go-unit-tests> 2026-06-28 14:39:17.900 UTC [70343] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC954niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (7.63ms)955niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (384.83µs)956niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (679.88µs)957niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (9.84ms)958niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (10.1ms)959niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (345.08µs)960niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (359.63µs)961niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (700.04µs)962niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (687.21µs)963niks3-go-unit-tests> 2026-06-28 14:39:17.927 UTC [70344] ERROR:  relation "goose_db_version" does not exist at character 36964niks3-go-unit-tests> 2026-06-28 14:39:17.927 UTC [70344] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC965niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (7.96ms)966niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000967niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (5.84ms)968niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000969niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (885.67µs)970niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (810.25µs)971niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (209.75µs)972niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2973niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (214.5µs)974niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2975niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.57s)976niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask977niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)978niks3-go-unit-tests> === CONT  TestMetricsInventory979niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.50s)980niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest981niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)982niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug983niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (11.73ms)984niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000985niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (20.51ms)986niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (582.25µs)987niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.98ms)988niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (232.21µs)989niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2990niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.09ms)991niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.24ms)992niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 20260628120000993niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.52ms)994niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (205.75µs)995niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 2996niks3-go-unit-tests> 2026-06-28 14:39:17.948 UTC [70349] ERROR:  relation "goose_db_version" does not exist at character 36997niks3-go-unit-tests> 2026-06-28 14:39:17.948 UTC [70349] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC998niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (12.23ms)999niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (356.92µs)1000niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (627.13µs)1001niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (818.83µs)1002niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 202606281200001003niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (797.83µs)1004niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (190.46µs)1005niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 21006niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.52s)1007niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty1008niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)1009niks3-go-unit-tests> === CONT  TestGenerateLandingPage1010niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)1011niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams1012niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1013niks3-go-unit-tests> === CONT  TestClientErrorHandling1014niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath1015niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath1016niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken1017niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken1018niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable1019niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable1020niks3-go-unit-tests> === CONT  TestService_healthCheckHandler1021niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20241026095416_initial_model.sql (8.95ms)1022niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251210153512_drop_unused_gin_index.sql (436.42µs)1023niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20251218171726_add_pins.sql (1.82ms)1024niks3-go-unit-tests> 2026/06/28 14:39:17 OK   20260628120000_add_object_size_and_stats.sql (2.41ms)1025niks3-go-unit-tests> 2026/06/28 14:39:17 goose: successfully migrated database to version: 202606281200001026niks3-go-unit-tests> 2026/06/28 14:39:17 OK   1_commit_pending_closure.sql (1.33ms)1027niks3-go-unit-tests> 2026/06/28 14:39:17 OK   2_object_stats_trigger.sql (395.63µs)1028niks3-go-unit-tests> 2026/06/28 14:39:17 goose: up to current file version: 21029niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.53s)1030niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams1031niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1032niks3-go-unit-tests> === CONT  TestGCMetrics1033niks3-go-unit-tests> 2026-06-28 14:39:18.008 UTC [70354] ERROR:  relation "goose_db_version" does not exist at character 361034niks3-go-unit-tests> 2026-06-28 14:39:18.008 UTC [70354] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1035niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (10.22ms)1036niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (702.21µs)1037niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (789.25µs)1038niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (1.57ms)1039niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001040niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.33ms)1041niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (203.92µs)1042niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21043niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.48s)1044niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences1045niks3-go-unit-tests> 2026-06-28 14:39:18.049 UTC [70357] ERROR:  relation "goose_db_version" does not exist at character 361046niks3-go-unit-tests> 2026-06-28 14:39:18.049 UTC [70357] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1047niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (10.78ms)1048niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (449.54µs)1049niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (659.33µs)1050niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (11.3ms)1051niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001052niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.12ms)1053niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (247.67µs)1054niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21055niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.46s)1056niks3-go-unit-tests> === CONT  TestPinProtectsFromGC1057niks3-go-unit-tests> 2026-06-28 14:39:18.120 UTC [70360] ERROR:  relation "goose_db_version" does not exist at character 361058niks3-go-unit-tests> 2026-06-28 14:39:18.120 UTC [70360] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1059niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (6.08ms)1060niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (465.25µs)1061niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.87ms)1062niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (1.25ms)1063niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001064niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.08ms)1065niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (359.29µs)1066niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21067niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1068niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1069niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.39s)1070niks3-go-unit-tests> === CONT  TestClientWithDependencies1071niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1072niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1073niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1074niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1075niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1076niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1077niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1078niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.83s)1079niks3-go-unit-tests> === CONT  TestClientMultipleUploads1080niks3-go-unit-tests> 2026-06-28 14:39:18.202 UTC [70365] ERROR:  relation "goose_db_version" does not exist at character 361081niks3-go-unit-tests> 2026-06-28 14:39:18.202 UTC [70365] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1082niks3-go-unit-tests> 2026-06-28 14:39:18.246 UTC [70366] ERROR:  relation "goose_db_version" does not exist at character 361083niks3-go-unit-tests> 2026-06-28 14:39:18.246 UTC [70366] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1084niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (46.26ms)1085niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (750µs)1086niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.49ms)1087niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (3.34ms)1088niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001089niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.24ms)1090niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (224.75µs)1091niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21092niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket281093niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (11.83ms)1094niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (1.09ms)1095niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.17ms)1096niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (2.9ms)1097niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001098niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (901.08µs)1099niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (192.25µs)1100niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21101niks3-go-unit-tests> 2026-06-28 14:39:18.289 UTC [70368] ERROR:  relation "goose_db_version" does not exist at character 361102niks3-go-unit-tests> 2026-06-28 14:39:18.289 UTC [70368] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1103niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.36s)1104niks3-go-unit-tests> === CONT  TestClientIntegration1105niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1106niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-70157-115164581/TestNARDeduplicationMetadataUploadBug3847409166/001/store/jx6w86m4zkqcgk94i1gwscaqwjrlx18i-file1.txt1107niks3-go-unit-tests> 2026-06-28 14:39:18.336 UTC [70373] ERROR:  relation "goose_db_version" does not exist at character 361108niks3-go-unit-tests> 2026-06-28 14:39:18.336 UTC [70373] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1109niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (34.46ms)1110niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (384.67µs)1111niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (712.04µs)1112niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (2.85ms)1113niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001114niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.11ms)1115niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (217µs)1116niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21117niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.38s)1118niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1119niks3-go-unit-tests> 2026/06/28 14:39:18 INFO OIDC provider initialized name=test1120niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (8.02ms)1121niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (481.67µs)1122niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.13ms)1123niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (6.02ms)1124niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001125niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (887.63µs)1126niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (226.42µs)1127niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21128niks3-go-unit-tests> 2026-06-28 14:39:18.371 UTC [70378] ERROR:  relation "goose_db_version" does not exist at character 361129niks3-go-unit-tests> 2026-06-28 14:39:18.371 UTC [70378] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1130niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Aborted multipart uploads count=01131niks3-go-unit-tests> 2026/06/28 14:39:18 WARN Force mode enabled - objects will be deleted immediately without grace period1132niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=01133niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Vacuumed table table=pending_closures1134niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Vacuumed table table=pending_objects1135niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Vacuumed table table=multipart_uploads1136niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Vacuumed table table=closures1137niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Vacuumed table table=objects1138niks3-go-unit-tests> --- PASS: TestGCMetrics (0.41s)1139niks3-go-unit-tests> === CONT  TestCacheConfigHandler1140niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1141niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1142niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1143niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1144niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1145niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1146niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1147niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1148niks3-go-unit-tests> === CONT  TestCacheStatsHandler1149niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (6.9ms)1150niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (655.88µs)1151niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (2.14ms)1152niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1153niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1154niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (3.12ms)1155niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001156niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.56ms)1157niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (499.96µs)1158niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21159niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1160niks3-go-unit-tests> 2026-06-28 14:39:18.428 UTC [70384] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-28 14:39:18.428 UTC [70384] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (11.52ms)1163niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (621.63µs)1164niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1165niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (9.31ms)1166niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (2.07ms)1167niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001168niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.48ms)1169niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (240.71µs)1170niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21171niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1172niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading jx6w86m4zkqcgk94i1gwscaqwjrlx18i-file1.txt (160B)1173niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket331174niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1175niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=1 count=11176niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1177niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1178niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=11179niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (97ms)1180niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1181niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1182niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-70157-115164581/TestNARDeduplicationMetadataUploadBug3847409166/001/store/jx6w86m4zkqcgk94i1gwscaqwjrlx18i-file1.txt1183niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1184niks3-go-unit-tests>         Compression: zstd1185niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1186niks3-go-unit-tests>         NarSize: 1601187niks3-go-unit-tests>         References: 1188niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1189niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1190niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1191niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1192niks3-go-unit-tests> 2026-06-28 14:39:18.480 UTC [70388] ERROR:  relation "goose_db_version" does not exist at character 361193niks3-go-unit-tests> 2026-06-28 14:39:18.480 UTC [70388] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1194niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1195niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1196niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201197niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101198niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101199niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.10s)1200niks3-go-unit-tests> === CONT  TestClientCADerivations1201niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (6.96ms)1202niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (544.54µs)1203niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.88ms)1204niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (30.12ms)1205niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001206niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (922.71µs)1207niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (212µs)1208niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21209niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket341210niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1211niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-70157-115164581/TestNARDeduplicationMetadataUploadBug3847409166/001/store/kb311j10v681r858z6djf8ihjzvhh9y6-file2.txt1212niks3-go-unit-tests> 2026-06-28 14:39:18.569 UTC [70396] ERROR:  relation "goose_db_version" does not exist at character 361213niks3-go-unit-tests> 2026-06-28 14:39:18.569 UTC [70396] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1214niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1215niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-70157-115164581/TestPinProtectsFromGC4036263709/001/store/9rishj7nhw30sklxk0dg764g1asjncl8-pinned-file.txt1216niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-70157-115164581/TestPinProtectsFromGC4036263709/001/store/9iyhnx8snc24b8hk458wvwf7c7lh6lhl-unpinned-file.txt1217niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (33ms)1218niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.58s)1219niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1220niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (917.71µs)1221niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (2.77ms)1222niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (5.22ms)1223niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001224niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.48ms)1225niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (549.67µs)1226niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21227niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket351228niks3-go-unit-tests> 2026-06-28 14:39:18.628 UTC [70407] ERROR:  relation "goose_db_version" does not exist at character 361229niks3-go-unit-tests> 2026-06-28 14:39:18.628 UTC [70407] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1230niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (9.48ms)1231niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (722µs)1232niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.37ms)1233niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (3.19ms)1234niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001235niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (2.18ms)1236niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (373.96µs)1237niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21238niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket361239niks3-go-unit-tests> 2026-06-28 14:39:18.660 UTC [70416] ERROR:  relation "goose_db_version" does not exist at character 361240niks3-go-unit-tests> 2026-06-28 14:39:18.660 UTC [70416] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1241niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1242niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1243niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1244niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=2 count=11245niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1246niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1247niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=21248niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (83ms)1249niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1250niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1251niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-70157-115164581/TestNARDeduplicationMetadataUploadBug3847409166/001/store/kb311j10v681r858z6djf8ihjzvhh9y6-file2.txt1252niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1253niks3-go-unit-tests>         Compression: zstd1254niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1255niks3-go-unit-tests>         NarSize: 1601256niks3-go-unit-tests>         References: 1257niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1258niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1259niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1260niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1261niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.77s)1262niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1263niks3-go-unit-tests> === NAME  TestClientMultipleUploads1264niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-70157-115164581/TestClientMultipleUploads97131859/001/store/ni0gsn6xcya3pxyzx61nh0w9bw7r0wwl-test-file-0.txt1265niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (51.32ms)1266niks3-go-unit-tests> === NAME  TestClientIntegration1267niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-70157-115164581/TestClientIntegration1117077086/002/store/jbjdda4flc9gqrkyh8bznp2354w7cs0z-test-file.txt1268niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (1.18ms)1269niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (2.56ms)1270niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1271niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (3.61ms)1272niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001273niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (984.75µs)1274niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (246.25µs)1275niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21276niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1277niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1278niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1279niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1280niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1281niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1282niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1283niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1284niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1285niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1286niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 9rishj7nhw30sklxk0dg764g1asjncl8-pinned-file.txt (128B)1287niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1288niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=1 count=11289niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1290niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1291niks3-go-unit-tests> === NAME  TestClientMultipleUploads1292niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-70157-115164581/TestClientMultipleUploads97131859/001/store/ii6iprf5awdd4q0l9mg28xid6yj9x6zc-test-file-1.txt1293niks3-go-unit-tests> 2026-06-28 14:39:18.746 UTC [70429] ERROR:  relation "goose_db_version" does not exist at character 361294niks3-go-unit-tests> 2026-06-28 14:39:18.746 UTC [70429] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1295niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=11296niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (114ms)1297niks3-go-unit-tests> === NAME  TestClientWithDependencies1298niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-70157-115164581/TestClientWithDependencies1384215779/001/store/f89p3wwwl0v5yf4xim12ygf447m6bpw6-test-script1299niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (8.37ms)1300niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (854.17µs)1301niks3-go-unit-tests> === NAME  TestClientMultipleUploads1302niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-70157-115164581/TestClientMultipleUploads97131859/001/store/hywlwj21h8656a41mizdla74rcbkgihy-test-file-2.txt1303niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.37ms)1304niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (2.96ms)1305niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001306niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.46ms)1307niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (391.54µs)1308niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21309niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.41s)1310niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1311niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/1312niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1313niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete multipart upload request method=POST path=/1314niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1315niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received request for more parts method=POST path=/1316niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1317niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/1318niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1319niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1320niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1321niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1322niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1323niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1324niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1325niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1326niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1327niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1328niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1329niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1330niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1331niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1332niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1333niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1334niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1335niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1336niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1337niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1338niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1339niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1340niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1341niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1342niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1343niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1344niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1345niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1346niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1347niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1348niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1349niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1350niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1351niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1352niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1353niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1354niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1355niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1356niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1357niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1358niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1359niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1360niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1361niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1362niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1363niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1364niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1365niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1366niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1367niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1368niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1369niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1370niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1371niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1372niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1373niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1374niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1375niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1376niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1377niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1378niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received request for more parts method=POST path=/1379niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1380niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete multipart upload request method=POST path=/1381niks3-go-unit-tests> === NAME  TestClientWithDependencies1382niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1383niks3-go-unit-tests> 2026-06-28 14:39:18.823 UTC [70443] ERROR:  relation "goose_db_version" does not exist at character 361384niks3-go-unit-tests> 2026-06-28 14:39:18.823 UTC [70443] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1385niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1386niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1387niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading jbjdda4flc9gqrkyh8bznp2354w7cs0z-test-file.txt (152B)1388niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1389niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1390niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/1391niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=1 count=11392niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1393niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1394niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (7.63ms)1395niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (736.21µs)1396niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.43ms)1397niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=11398niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (94ms)1399niks3-go-unit-tests> === NAME  TestClientIntegration1400niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1401niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-70157-115164581/TestClientIntegration1117077086/002/store/jbjdda4flc9gqrkyh8bznp2354w7cs0z-test-file.txt1402niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1403niks3-go-unit-tests>         Compression: zstd1404niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11405niks3-go-unit-tests>         NarSize: 1521406niks3-go-unit-tests>         References: 1407niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11408niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (1.53ms)1409niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001410niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1411niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1412niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1413niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1414niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.66ms)1415niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (2ms)1416niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21417niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created nix-cache-info in bucket bucket=bucket391418niks3-go-unit-tests> 2026-06-28 14:39:18.875 UTC [70455] ERROR:  relation "goose_db_version" does not exist at character 361419niks3-go-unit-tests> 2026-06-28 14:39:18.875 UTC [70455] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1420niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1421niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1422niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 9iyhnx8snc24b8hk458wvwf7c7lh6lhl-unpinned-file.txt (128B)1423niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1424niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=2 count=11425niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1426niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1427niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=21428niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (92ms)1429niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (11.26ms)1430niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Starting cleanup of old closures method=DELETE path=/api/closures1431niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Garbage collection started1432niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (764.42µs)1433niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.75ms)1434niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Aborted multipart uploads count=01435niks3-go-unit-tests> 2026/06/28 14:39:18 WARN Force mode enabled - objects will be deleted immediately without grace period1436niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1437niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1438niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received create pin request method=POST path=/api/pins/myapp1439niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (38.97ms)1440niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001441niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (9.96ms)1442niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1443niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-70157-115164581/TestPinProtectsFromGC4036263709/001/store/9rishj7nhw30sklxk0dg764g1asjncl8-pinned-file.txt narinfo_key=9rishj7nhw30sklxk0dg764g1asjncl8.narinfo1444niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (523.96µs)1445niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21446niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received uploads request method=POST path=/api/pending_closures1447niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1448niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Starting cleanup of old closures method=DELETE path=/api/closures1449niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading f89p3wwwl0v5yf4xim12ygf447m6bpw6-test-script (136B)1450niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Garbage collection started1451niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1452niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading ni0gsn6xcya3pxyzx61nh0w9bw7r0wwl-test-file-0.txt (160B)1453niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading ii6iprf5awdd4q0l9mg28xid6yj9x6zc-test-file-1.txt (160B)1454niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading hywlwj21h8656a41mizdla74rcbkgihy-test-file-2.txt (160B)1455niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1456niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.33s)1457niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1458niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1459niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1460niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1461niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1462niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1463niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1464niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1465niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1466niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1467niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1468niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1469niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1470niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1471niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1472niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1473niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1474niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21475niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1476niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1477niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1478niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1479niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1480niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1481niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1482niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1483niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1484niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1485niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1486niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1487niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1488niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1489niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1490niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1491niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1492niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1493niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1494niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1495niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1496niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1497niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1498niks3-go-unit-tests> === CONT  TestParseSingleRange/none1499niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1500niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1501niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1502niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1503niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1504niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1505niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1506niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1507niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1508niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1509niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1510niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1511niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1512niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1513niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1514niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1515niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1516niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1517niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1518niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1519niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1520niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1521niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1522niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1523niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1524niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1525niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1526niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1527niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1528niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1529niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1530niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1531niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1532niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1533niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1534niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1535niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1536niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1537niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Aborted multipart uploads count=01538niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=3 count=11539niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=1 count=11540niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 1 narinfos1541niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1542niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=1 count=11543niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1544niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Signed narinfos id=2 count=11545niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Uploading 3 narinfos1546niks3-go-unit-tests> 2026/06/28 14:39:18 WARN Force mode enabled - objects will be deleted immediately without grace period1547niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1548niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1549niks3-go-unit-tests> 2026-06-28 14:39:18.956 UTC [70466] ERROR:  relation "goose_db_version" does not exist at character 361550niks3-go-unit-tests> 2026-06-28 14:39:18.956 UTC [70466] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1551niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=11552niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (93ms)1553niks3-go-unit-tests> === NAME  TestClientWithDependencies1554niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-70157-115164581/TestClientWithDependencies1384215779/001/store) requires matching store prefix1555niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.82s)1556niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1557niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=11558niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1559niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=21560niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1561niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Completed upload id=31562niks3-go-unit-tests> 2026/06/28 14:39:18 INFO Upload complete. (149ms)1563niks3-go-unit-tests> === NAME  TestClientMultipleUploads1564niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 185.3445ms1565niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20241026095416_initial_model.sql (6.97ms)1566niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.79s)1567niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1568niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251210153512_drop_unused_gin_index.sql (1.4ms)1569niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20251218171726_add_pins.sql (1.86ms)1570niks3-go-unit-tests> 2026-06-28 14:39:18.982 UTC [70472] ERROR:  relation "goose_db_version" does not exist at character 361571niks3-go-unit-tests> 2026-06-28 14:39:18.982 UTC [70472] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1572niks3-go-unit-tests> 2026/06/28 14:39:18 OK   20260628120000_add_object_size_and_stats.sql (18.01ms)1573niks3-go-unit-tests> 2026/06/28 14:39:18 goose: successfully migrated database to version: 202606281200001574niks3-go-unit-tests> 2026/06/28 14:39:18 OK   1_commit_pending_closure.sql (1.87ms)1575niks3-go-unit-tests> 2026/06/28 14:39:18 OK   2_object_stats_trigger.sql (610.13µs)1576niks3-go-unit-tests> 2026/06/28 14:39:18 goose: up to current file version: 21577niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1578niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: bound subjects configured but subject DN unavailable1579niks3-go-unit-tests> 2026/06/28 14:39:18 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1580niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.30s)1581niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1582niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1583niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1584niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1585niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1586niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1587niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1588niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1589niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1590niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1591niks3-go-unit-tests> 2026/06/28 14:39:18 INFO OIDC auth successful provider=test1592niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1593niks3-go-unit-tests> 2026/06/28 14:39:18 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]1594niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1595niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1596niks3-go-unit-tests> 2026/06/28 14:39:19 WARN Authentication failed token_preview=eyJhbGciOi...iPJXSh9vng 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]1597niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.38s)1598niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1599niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1600niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1601niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1602niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20241026095416_initial_model.sql (25.96ms)1603niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251210153512_drop_unused_gin_index.sql (746.04µs)1604niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251218171726_add_pins.sql (1.63ms)1605niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20260628120000_add_object_size_and_stats.sql (2.8ms)1606niks3-go-unit-tests> 2026/06/28 14:39:19 goose: successfully migrated database to version: 202606281200001607niks3-go-unit-tests> 2026/06/28 14:39:19 OK   1_commit_pending_closure.sql (1.5ms)1608niks3-go-unit-tests> 2026/06/28 14:39:19 OK   2_object_stats_trigger.sql (261.92µs)1609niks3-go-unit-tests> 2026/06/28 14:39:19 goose: up to current file version: 21610niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.30s)1611niks3-go-unit-tests> === NAME  TestClientCADerivations1612niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-70157-115164581/TestClientCADerivations1326598137/001/store/b7cfv9gxdn34xm0dv827ng955q257akh-ca-test1613niks3-go-unit-tests> 2026-06-28 14:39:19.119 UTC [70481] ERROR:  relation "goose_db_version" does not exist at character 361614niks3-go-unit-tests> 2026-06-28 14:39:19.119 UTC [70481] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1615niks3-go-unit-tests> 2026-06-28 14:39:19.119 UTC [70482] ERROR:  relation "goose_db_version" does not exist at character 361616niks3-go-unit-tests> 2026-06-28 14:39:19.119 UTC [70482] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1617niks3-go-unit-tests> 2026/06/28 14:39:19 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_closures1618niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20241026095416_initial_model.sql (5.12ms)1619niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20241026095416_initial_model.sql (5.81ms)1620niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.03s)1621niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1622niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1623niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.30s)1624niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251210153512_drop_unused_gin_index.sql (687.96µs)1625niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251210153512_drop_unused_gin_index.sql (935.71µs)1626niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251218171726_add_pins.sql (1.34ms)1627niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20251218171726_add_pins.sql (1.67ms)1628niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20260628120000_add_object_size_and_stats.sql (1.51ms)1629niks3-go-unit-tests> 2026/06/28 14:39:19 goose: successfully migrated database to version: 202606281200001630niks3-go-unit-tests> 2026/06/28 14:39:19 OK   20260628120000_add_object_size_and_stats.sql (1.31ms)1631niks3-go-unit-tests> 2026/06/28 14:39:19 goose: successfully migrated database to version: 202606281200001632niks3-go-unit-tests> 2026/06/28 14:39:19 OK   1_commit_pending_closure.sql (884.38µs)1633niks3-go-unit-tests> 2026/06/28 14:39:19 OK   1_commit_pending_closure.sql (901.54µs)1634niks3-go-unit-tests> 2026/06/28 14:39:19 OK   2_object_stats_trigger.sql (255.79µs)1635niks3-go-unit-tests> 2026/06/28 14:39:19 goose: up to current file version: 21636niks3-go-unit-tests> 2026/06/28 14:39:19 OK   2_object_stats_trigger.sql (281.46µs)1637niks3-go-unit-tests> 2026/06/28 14:39:19 goose: up to current file version: 21638niks3-go-unit-tests> === NAME  TestClientCADerivations1639niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1640niks3-go-unit-tests> 2026/06/28 14:39:19 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=196.385843ms 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 14:39:19 INFO Received uploads request method=POST path=/api/pending_closures1642niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1643niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Uploading b7cfv9gxdn34xm0dv827ng955q257akh-ca-test (144B)1644niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1645niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Signed narinfos id=1 count=11646niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Uploading 1 narinfos1647niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1648niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Completed upload id=11649niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Upload complete. (64ms)1650niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-70157-115164581/TestClientCADerivations1326598137/001/store/b7cfv9gxdn34xm0dv827ng955q257akh-ca-test1651niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1652niks3-go-unit-tests>         Compression: zstd1653niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1654niks3-go-unit-tests>         NarSize: 1441655niks3-go-unit-tests>         References: 1656niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-70157-115164581/TestClientCADerivations1326598137/001/store/bcjb8pfg4pfm70p1jcmy47wd58km18z2-ca-test.drv1657niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1658niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1659niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1660niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1661niks3-go-unit-tests> 2026/06/28 14:39:19 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=01662niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=pending_closures1663niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=pending_objects1664niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=multipart_uploads1665niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=closures1666niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=objects1667niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket39?endpoint=http://localhost:52626&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-70157-115164581/TestClientCADerivations1326598137/001/store'1668niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11669niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.80s)1670niks3-go-unit-tests> 2026/06/28 14:39:19 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1671niks3-go-unit-tests> 2026/06/28 14:39:19 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=01672niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=pending_closures1673niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=pending_objects1674niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=multipart_uploads1675niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=closures1676niks3-go-unit-tests> 2026/06/28 14:39:19 INFO Vacuumed table table=objects1677niks3-go-unit-tests> 2026/06/28 14:39:19 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=388.157861ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1678niks3-go-unit-tests> 2026/06/28 14:39:19 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=863.552471ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1679niks3-go-unit-tests> 2026/06/28 14:39:20 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.648647545s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1680niks3-go-unit-tests> 2026/06/28 14:39:20 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01681niks3-go-unit-tests> === NAME  TestClientIntegration1682niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1683niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1684niks3-go-unit-tests> --- PASS: TestClientIntegration (2.61s)1685niks3-go-unit-tests> 2026/06/28 14:39:20 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01686niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1687niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1688niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.87s)1689niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1690niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.23s)1691niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.33s)1692niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.39s)1693niks3-go-unit-tests> 2026/06/28 14:39:22 WARN Rate limiter enabled after throttle name=s3-test rate=51694niks3-go-unit-tests> 2026/06/28 14:39:22 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1695niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1696niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101697niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001698niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.39s)1699niks3-go-unit-tests> PASS1700niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:23.143105Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:52657"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(6)"}1701niks3-go-unit-tests> 2026-06-28 14:39:23.203 UTC [70192] LOG:  received smart shutdown request1702niks3-go-unit-tests> 2026-06-28 14:39:23.205 UTC [70192] LOG:  background worker "logical replication launcher" (PID 70199) exited with exit code 11703niks3-go-unit-tests> 2026-06-28 14:39:23.214 UTC [70194] LOG:  shutting down1704niks3-go-unit-tests> 2026-06-28 14:39:23.214 UTC [70194] LOG:  checkpoint starting: shutdown immediate1705niks3-go-unit-tests> 2026-06-28 14:39:24.245 UTC [70194] LOG:  checkpoint complete: wrote 13321 buffers (81.3%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.783 s, sync=0.245 s, total=1.031 s; sync files=14421, longest=0.001 s, average=0.001 s; distance=197509 kB, estimate=197509 kB; lsn=0/D5F4138, redo lsn=0/D5F41381706niks3-go-unit-tests> 2026-06-28 14:39:24.247 UTC [70192] LOG:  database system is shut down1707niks3-go-unit-tests> Running OIDC tests...1708niks3-go-unit-tests> === RUN   TestGlobMatch1709niks3-go-unit-tests> === PAUSE TestGlobMatch1710niks3-go-unit-tests> === RUN   TestAudienceForIssuer1711niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1712niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1713niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1714niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1715niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1716niks3-go-unit-tests> === RUN   TestValidateToken_Expired1717niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1718niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1719niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1720niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1721niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1722niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1723niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1724niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1725niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1726niks3-go-unit-tests> === CONT  TestGlobMatch1727niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1728niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1729niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1730niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1731niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1732niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1733niks3-go-unit-tests> === CONT  TestAudienceForIssuer1734niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1735niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1736niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1737niks3-go-unit-tests> === CONT  TestValidateToken_Expired1738niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1739niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1740niks3-go-unit-tests> === RUN   TestGlobMatch/*_1741niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1742niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1743niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1744niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1745niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1746niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1747niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1748niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1749niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1750niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1751niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1752niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1753niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1754niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1755niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1756niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1757niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1758niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1759niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1760niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1761niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1762niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1763niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1764niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1765niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1766niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1767niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1768niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01769niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01770niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1771niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1772niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1773niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1774niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1775niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1776niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1777niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1778niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1779niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1780niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1781niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1782niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1783niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1784niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1785niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1786niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1787niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1788niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1789niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1790niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1791niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1792niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1793niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1794niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1795niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01796niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1797niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1798niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1799niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1800niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1801niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1802niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1803niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1804niks3-go-unit-tests> === CONT  TestGlobMatch/*_1805niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1806niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1807niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1808niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1809niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1810niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1811niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1812niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1813niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1814niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1815niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1816niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1837niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test1838niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=provider11839niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test1840niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test1841niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=provider11842niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test1843niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test1844niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=provider21845niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1846niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1847niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1848niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1849niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1850niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1851niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1852niks3-go-unit-tests> PASS1853niks3-go-unit-tests> Running hook tests...1854niks3-go-unit-tests> === RUN   TestSendPathsEmpty1855niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1856niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1857niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1858niks3-go-unit-tests> === RUN   TestQueueDeduplication1859niks3-go-unit-tests> === PAUSE TestQueueDeduplication1860niks3-go-unit-tests> === RUN   TestQueueRemove1861niks3-go-unit-tests> === PAUSE TestQueueRemove1862niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1863niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1864niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1865niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1866niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1867niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1868niks3-go-unit-tests> === RUN   TestServerClientIntegration1869niks3-go-unit-tests> === PAUSE TestServerClientIntegration1870niks3-go-unit-tests> === RUN   TestServerQueueError1871niks3-go-unit-tests> === PAUSE TestServerQueueError1872niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1873niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1874niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1875niks3-go-unit-tests>         PASS1876niks3-go-unit-tests>         1877niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1878niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1879niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1880niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1881niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1882niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1883niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1884niks3-go-unit-tests> === CONT  TestSendPathsEmpty1885niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1886niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1887niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1888niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1889niks3-go-unit-tests> === CONT  TestQueueRemove1890niks3-go-unit-tests> === CONT  TestQueueDeduplication1891niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1892niks3-go-unit-tests> === CONT  TestServerQueueError1893niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1894niks3-go-unit-tests> === CONT  TestServerClientIntegration1895niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1896niks3-go-unit-tests> 2026/06/28 14:39:25 ERROR Failed to queue paths error="permission denied" count=11897niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1898niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1899niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1900niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload queue status pending=21901niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading batch count=11902niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1903niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1904niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1905niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload queue status pending=21906niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading batch count=21907niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1908niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload queue status pending=21909niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-70157-115164581/TestWorkerSkipsGCdPaths3427292397/002/nonexistent1910niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading batch count=11911niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1912niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1913niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1914niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1915niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.13s)1916niks3-go-unit-tests> PASS19171918post-build step Upload coverage to codecov: ok1919Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests