nixbot

1this derivation will be built:2  /nix/store/rkazl0lvx7vh6y3bavlvlk70a0csv0vh-niks3-go-unit-tests.drv3building '/nix/store/rkazl0lvx7vh6y3bavlvlk70a0csv0vh-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/rkazl0lvx7vh6y3bavlvlk70a0csv0vh-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  TestScriptTokenCachesUntilRefresh76niks3-go-unit-tests> === CONT  TestSetClientTLSErrors77niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand78niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)79niks3-go-unit-tests> === CONT  TestConvertHashToNix3280niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails81niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON82niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken83niks3-go-unit-tests> === CONT  TestDumpPathWriterError84niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility85niks3-go-unit-tests> === CONT  TestGetStorePathHash86niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path87niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path88niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)89niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error90niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3291niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3292niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format93niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format94niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format95niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format96niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash97niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error98niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error99niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error100niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)101niks3-go-unit-tests> === CONT  TestEncodeNixBase32102niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash103niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash104niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)105niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error106niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error107niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input108niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input109niks3-go-unit-tests> === CONT  TestFileTokenMissing110niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall111niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon112niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon113niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI114niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI115niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512116niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512117niks3-go-unit-tests> === CONT  TestFileTokenEmpty118niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file119niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file120niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file121niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file122niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file123niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file124niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file125niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file126niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)127niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer128niks3-go-unit-tests> === CONT  TestDumpPathSingleFile129niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists130niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists131niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing132niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing133niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix134niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)135niks3-go-unit-tests> === CONT  TestPartSizeForNAR136niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum137niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum138niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum139niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum140niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum141niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum142niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts143niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts144niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB145niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB146niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object147niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object148niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB149niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB150niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)151niks3-go-unit-tests> === CONT  TestStaticToken152niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)153niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody154niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches155niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport156niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)157niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)158niks3-go-unit-tests> === CONT  TestSetClientTLS159niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter enabled after throttle name=server-test rate=5160niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:55565161niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter backed off name=server-test rate=5162niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:55565163niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)164niks3-go-unit-tests> === CONT  TestShellSplitErrors165niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)166niks3-go-unit-tests> === CONT  TestShellSplit167niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)168niks3-go-unit-tests> === CONT  TestCaseHackSuffix169niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)170niks3-go-unit-tests> === CONT  TestRateLimiterFeedback171niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter172niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter173niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter174niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter175niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter176niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter177niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter178niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter179niks3-go-unit-tests> === CONT  TestResolveStorePath180niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert181niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert182niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA183niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA184niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport185niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport186niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess187niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter enabled after throttle name=server-test rate=5188niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)189niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility190niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field191niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field192niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text193niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text194niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive195niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive196niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text197niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text198niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method199niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method200niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths201niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths202niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths203niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths204niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths205niks3-go-unit-tests> === CONT  TestParsePathInfoJSON206niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format207niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format208niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format209niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format210niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input211niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input212niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only213niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only214niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON215niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON216niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32217niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format218niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format219niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)220niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)221niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)222niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)223niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path224niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash225niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error226niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error227niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error228niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)229niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)230niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)231niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)232niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)233niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)234niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)235niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI236niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512237niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon238niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input239niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)240niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)241niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)242niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file243niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)244niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)245niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)246niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)247niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)248niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file249niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file250niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.02s)251niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file252niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists253niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing254niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum255niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB256niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object257niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB258niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts259niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum260niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum261niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)262niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)263niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)264niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)265niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)266niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)267niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)268niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)269niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter270niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)271niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)272niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)273niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)274niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)275niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter enabled after throttle name=server-test rate=5276niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:55572277niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter backed off name=server-test rate=5278niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter279niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter280niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter281niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert282niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)283niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)284niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)285niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport286niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter enabled after throttle name=server-test rate=5287niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:55580288niks3-go-unit-tests> 2026/06/29 09:13:42 WARN Rate limiter backed off name=server-test rate=5289niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)290niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)291niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)292niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)293niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)294niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA295niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field296niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths297niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method298niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text299niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive300niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text301niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format302niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)303niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)304niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)305niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)306niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)307niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)308niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths309niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)310niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)311niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)312niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON313niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input314niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format315niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only316niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)317niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)318niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)319niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)320niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)321niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)322niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.04s)323niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.03s)324niks3-go-unit-tests> 2026/06/29 09:13:42 http: TLS handshake error from 127.0.0.1:55583: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.03s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.05s)330niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)331niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (3.95s)332niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (3.94s)333niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (3.96s)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-99674-3193245854/postgres2522347879/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-99674-3193245854/postgres2522347879/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> 2026-06-29 09:13:48.303 UTC [99712] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit364niks3-go-unit-tests> 2026-06-29 09:13:48.304 UTC [99712] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-99674-3193245854/postgres2522347879/.s.PGSQL.5432"365niks3-go-unit-tests> 2026-06-29 09:13:48.305 UTC [99716] LOG:  database system was shut down at 2026-06-29 09:13:48 UTC366niks3-go-unit-tests> 2026-06-29 09:13:48.306 UTC [99712] LOG:  database system is ready to accept connections367niks3-go-unit-tests> /nix/var/nix/builds/nix-99674-3193245854/postgres2522347879:5432 - accepting connections368niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only369niks3-go-unit-tests> {"timestamp":"2026-06-29T09:13:50.058748Z","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(7)"}370niks3-go-unit-tests> === RUN   TestService_AuthMiddleware371niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware372niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader373niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware377niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === RUN   TestCacheConfigHandler381niks3-go-unit-tests> === PAUSE TestCacheConfigHandler382niks3-go-unit-tests> === RUN   TestCacheStatsHandler383niks3-go-unit-tests> === PAUSE TestCacheStatsHandler384niks3-go-unit-tests> === RUN   TestClientCADerivations385niks3-go-unit-tests> === PAUSE TestClientCADerivations386niks3-go-unit-tests> === RUN   TestClientErrorHandling387niks3-go-unit-tests> === PAUSE TestClientErrorHandling388niks3-go-unit-tests> === RUN   TestClientIntegration389niks3-go-unit-tests> === PAUSE TestClientIntegration390niks3-go-unit-tests> === RUN   TestClientMultipleUploads391niks3-go-unit-tests> === PAUSE TestClientMultipleUploads392niks3-go-unit-tests> === RUN   TestClientWithDependencies393niks3-go-unit-tests> === PAUSE TestClientWithDependencies394niks3-go-unit-tests> === RUN   TestPinProtectsFromGC395niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC396niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun397niks3-go-unit-tests> 2026-06-29 09:13:50.437 UTC [99766] ERROR:  relation "goose_db_version" does not exist at character 36398niks3-go-unit-tests> 2026-06-29 09:13:50.437 UTC [99766] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC399niks3-go-unit-tests> 2026/06/29 09:13:50 OK   20241026095416_initial_model.sql (3.58ms)400niks3-go-unit-tests> 2026/06/29 09:13:50 OK   20251210153512_drop_unused_gin_index.sql (618.75µs)401niks3-go-unit-tests> 2026/06/29 09:13:50 OK   20251218171726_add_pins.sql (905.33µs)402niks3-go-unit-tests> 2026/06/29 09:13:50 OK   20260628120000_add_object_size_and_stats.sql (862.83µs)403niks3-go-unit-tests> 2026/06/29 09:13:50 goose: successfully migrated database to version: 20260628120000404niks3-go-unit-tests> 2026/06/29 09:13:50 OK   1_commit_pending_closure.sql (1ms)405niks3-go-unit-tests> 2026/06/29 09:13:50 OK   2_object_stats_trigger.sql (220.58µs)406niks3-go-unit-tests> 2026/06/29 09:13:50 goose: up to current file version: 2407niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.32s)408niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences409niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences410niks3-go-unit-tests> === RUN   TestGCMetrics411niks3-go-unit-tests> === PAUSE TestGCMetrics412niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew413niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew414niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams415niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams416niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams417niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams418niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty419niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty420niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest421niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest422niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask423niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask424niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates425niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates426niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail427niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail428niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight429niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight430niks3-go-unit-tests> === RUN   TestService_healthCheckHandler431niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler432niks3-go-unit-tests> === RUN   TestGenerateLandingPage433niks3-go-unit-tests> === PAUSE TestGenerateLandingPage434niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug435niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug436niks3-go-unit-tests> === RUN   TestMetricsInventory437niks3-go-unit-tests> === PAUSE TestMetricsInventory438niks3-go-unit-tests> === RUN   TestService_NativeMTLS439niks3-go-unit-tests> === PAUSE TestService_NativeMTLS440niks3-go-unit-tests> === RUN   TestServerTLSConfig441niks3-go-unit-tests> === PAUSE TestServerTLSConfig442niks3-go-unit-tests> === RUN   TestMultipartCleanup443niks3-go-unit-tests> === PAUSE TestMultipartCleanup444niks3-go-unit-tests> === RUN   TestObjectStatsTrigger445niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger446niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC447niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC448niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest449niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest450niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted451niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted452niks3-go-unit-tests> === RUN   TestParseSingleRange453niks3-go-unit-tests> === PAUSE TestParseSingleRange454niks3-go-unit-tests> === RUN   TestIsValidCachePath455niks3-go-unit-tests> === PAUSE TestIsValidCachePath456niks3-go-unit-tests> === RUN   TestReadProxyNarinfo457niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo458niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed459niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed460niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming461niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming462niks3-go-unit-tests> === RUN   TestReadProxy404463niks3-go-unit-tests> === PAUSE TestReadProxy404464niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath465niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath466niks3-go-unit-tests> === RUN   TestReadProxyHead467niks3-go-unit-tests> === PAUSE TestReadProxyHead468niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet469niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet470niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML471niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML472niks3-go-unit-tests> === RUN   TestReadProxyDisabled473niks3-go-unit-tests> === PAUSE TestReadProxyDisabled474niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest475niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest476niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload477niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload478niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists479niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists480niks3-go-unit-tests> === RUN   TestService_Rustfstest481niks3-go-unit-tests> === PAUSE TestService_Rustfstest482niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated483niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)484niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy485niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)486niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy487niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"488niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"489niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"490niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"491niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/29 09:13:50 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)497niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle498niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle499niks3-go-unit-tests> === RUN   TestProxyWriteTimeout500niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout501niks3-go-unit-tests> === RUN   TestIsValidUploadKey502niks3-go-unit-tests> === PAUSE TestIsValidUploadKey503niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys504niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys505niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody506niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody507niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler508niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler509niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler510niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler511niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity512niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity513niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered514niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered515niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT516niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT517niks3-go-unit-tests> === CONT  TestService_AuthMiddleware518niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT519niks3-go-unit-tests> === CONT  TestProxyWriteTimeout520niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo521niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered522niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail523niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler524niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest525niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet526niks3-go-unit-tests> === CONT  TestReadProxyHead527niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)528niks3-go-unit-tests> === CONT  TestReadProxy404529niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath530niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo531niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar532niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar533niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar534niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar535niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size536niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size537niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming538niks3-go-unit-tests> 2026-06-29 09:13:50.978 UTC [99788] ERROR:  relation "goose_db_version" does not exist at character 36539niks3-go-unit-tests> 2026-06-29 09:13:50.978 UTC [99788] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC540niks3-go-unit-tests> 2026-06-29 09:13:50.983 UTC [99789] ERROR:  relation "goose_db_version" does not exist at character 36541niks3-go-unit-tests> 2026-06-29 09:13:50.983 UTC [99789] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC542niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99791] ERROR:  relation "goose_db_version" does not exist at character 36543niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99791] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC544niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99792] ERROR:  relation "goose_db_version" does not exist at character 36545niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99792] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC546niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99790] ERROR:  relation "goose_db_version" does not exist at character 36547niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99790] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC548niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99793] ERROR:  relation "goose_db_version" does not exist at character 36549niks3-go-unit-tests> 2026-06-29 09:13:50.984 UTC [99793] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC550niks3-go-unit-tests> 2026-06-29 09:13:50.998 UTC [99794] ERROR:  relation "goose_db_version" does not exist at character 36551niks3-go-unit-tests> 2026-06-29 09:13:50.998 UTC [99794] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC552niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99795] ERROR:  relation "goose_db_version" does not exist at character 36553niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99795] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC554niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99797] ERROR:  relation "goose_db_version" does not exist at character 36555niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99797] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC556niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99796] ERROR:  relation "goose_db_version" does not exist at character 36557niks3-go-unit-tests> 2026-06-29 09:13:51.006 UTC [99796] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC558niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (11.19ms)559niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (11.04ms)560niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (610.29µs)561niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (551.04µs)562niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (12.7ms)563niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (11.85ms)564niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (602.63µs)565niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.35ms)566niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (621.63µs)567niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (12.96ms)568niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (13.22ms)569niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (2.16ms)570niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.54ms)571niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (6.71ms)572niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (1.01ms)573niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (882.17µs)574niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.59ms)575niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000576niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.95ms)577niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (771.08µs)578niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.4ms)579niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000580niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.67ms)581niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000582niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.31ms)583niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.84ms)584niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.74ms)585niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.34ms)586niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000587niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (563.63µs)588niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2589niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.57ms)590niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.08ms)591niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (902.92µs)592niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000593niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (858.79µs)594niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.62ms)595niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (5.32ms)596niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (590.46µs)597niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2598niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (538.21µs)599niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2600niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (698.71µs)601niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2602niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.7ms)603niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000604niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (593.79µs)605niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.64ms)606niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000607niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (5.89ms)608niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.77ms)609niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (7.05ms)610niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (345.13µs)611niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2612niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.3ms)613niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (698.92µs)614niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.37ms)615niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (288.92µs)616niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2617niks3-go-unit-tests> 2026/06/29 09:13:51 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"618niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.34s)619niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.49ms)620niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed621niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (846.79µs)622niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures623niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (668.71µs)624niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2625niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.41ms)626niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000627niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.71ms)628niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.69ms)629niks3-go-unit-tests> {"timestamp":"2026-06-29T09:13:51.020118Z","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(6)"}630niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.3ms)631niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000632niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.6ms)633niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (885µs)634niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2635niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.2ms)636niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000637niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.41ms)638niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.34s)639niks3-go-unit-tests> === CONT  TestReadProxyNarinfo640niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (1.44ms)641niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2642niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.35s)643niks3-go-unit-tests> === CONT  TestIsValidCachePath644niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo645niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo646niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars647niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars648niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst650niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz651niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz652niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2654niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.98ms)655niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed656niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed657niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls658niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls659niks3-go-unit-tests> === RUN   TestIsValidCachePath/log660niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log661niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation662niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation663niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info664niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info665niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html666niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received cleanup request method=DELETE path=/api/pending_closures667niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html668niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent669niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent670niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle671niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle672niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e673niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e674niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u675niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u676niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path677niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path678niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty679niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty680niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash681niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash682niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension683niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension684niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash685niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash686niks3-go-unit-tests> === CONT  TestParseSingleRange687niks3-go-unit-tests> === RUN   TestParseSingleRange/none688niks3-go-unit-tests> === PAUSE TestParseSingleRange/none689niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit690niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit691niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored692niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored693niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash694niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash695niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty696niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty697niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start698niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start699niks3-go-unit-tests> === RUN   TestParseSingleRange/closed700niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed701niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended702niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended703niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size704niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size705niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix706niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix707niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size708niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size709niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte710niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte711niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF712niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF713niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF714niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF715niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted716niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Aborted multipart uploads count=0717niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (606.29µs)718niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2719niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.35s)720niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys721niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.35s)722niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody723niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info724niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info725niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal726niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal727niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key728niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key729niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key730niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key731niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity732niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures733niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete multipart upload request method=POST path=/api/multipart/complete734niks3-go-unit-tests> 2026/06/29 09:13:51 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst735niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.36s)736niks3-go-unit-tests> === CONT  TestClientWithDependencies737niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.36s)738niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates739niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)740niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask741niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)742niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest743niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)744niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty745niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)746niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams747niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)748niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams749niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)750niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew751niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)752niks3-go-unit-tests> === CONT  TestGCMetrics753niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.36s)754niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences755niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received cleanup request method=DELETE path=/api/pending_closures756niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Aborted multipart uploads count=1757niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete758niks3-go-unit-tests> 2026-06-29 09:13:51.049 UTC [99796] ERROR:  Closure does not exist: id=1759niks3-go-unit-tests> 2026-06-29 09:13:51.049 UTC [99796] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE760niks3-go-unit-tests> 2026-06-29 09:13:51.049 UTC [99796] STATEMENT:  -- name: CommitPendingClosure :exec761niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)762niks3-go-unit-tests> 	763niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.37s)764niks3-go-unit-tests> === CONT  TestPinProtectsFromGC765niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure766niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure767niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart768niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart769niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts770niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts771niks3-go-unit-tests> === CONT  TestIsValidUploadKey772niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo773niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo774niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst775niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst776niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz777niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz778niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain779niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain780niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing781niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing782niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log783niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log784niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file785niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file786niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name787niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name788niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark789niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark790niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals791niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals792niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation793niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation794niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output795niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output796niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info797niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info798niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html799niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html800niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type801niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type802niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type803niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type804niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type805niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type806niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal807niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal808niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar809niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar810niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute811niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute812niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key813niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key814niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type815niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type816niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC817niks3-go-unit-tests> 2026-06-29 09:13:51.156 UTC [99837] ERROR:  relation "goose_db_version" does not exist at character 36818niks3-go-unit-tests> 2026-06-29 09:13:51.156 UTC [99837] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC819niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (10.72ms)820niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (1.34ms)821niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (2.35ms)822niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (4.55ms)823niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000824niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.28ms)825niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (237.67µs)826niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2827niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.18s)828niks3-go-unit-tests> === CONT  TestObjectStatsTrigger829niks3-go-unit-tests> 2026-06-29 09:13:51.202 UTC [99839] ERROR:  relation "goose_db_version" does not exist at character 36830niks3-go-unit-tests> 2026-06-29 09:13:51.202 UTC [99839] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC831niks3-go-unit-tests> 2026-06-29 09:13:51.220 UTC [99841] ERROR:  relation "goose_db_version" does not exist at character 36832niks3-go-unit-tests> 2026-06-29 09:13:51.220 UTC [99841] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC833niks3-go-unit-tests> 2026-06-29 09:13:51.242 UTC [99842] ERROR:  relation "goose_db_version" does not exist at character 36834niks3-go-unit-tests> 2026-06-29 09:13:51.242 UTC [99842] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC835niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (27.56ms)836niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (362.04µs)837niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (2.1ms)838niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (14.3ms)839niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (661.46µs)840niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (3.94ms)841niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000842niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (950.33µs)843niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (981.58µs)844niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (224.5µs)845niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2846niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures847niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (6.49ms)848niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000849niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.31ms)850niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (627.33µs)851niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2852niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (10.6ms)853niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (519.54µs)854niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.39ms)855niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.55ms)856niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000857niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.79ms)858niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (599.96µs)859niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2860niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.25s)861niks3-go-unit-tests> === CONT  TestMultipartCleanup862niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.26s)863niks3-go-unit-tests> === CONT  TestServerTLSConfig864niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA865niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA866niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file867niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file868niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file869niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file870niks3-go-unit-tests> === CONT  TestCacheConfigHandler871niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer872niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer873niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured874niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured875niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys876niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys877niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator878niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator879niks3-go-unit-tests> === CONT  TestService_NativeMTLS880niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete multipart upload request method=POST path=/api/multipart/complete881niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZDYyOTYzMDYtNDAyOS00YWJiLWE5M2UtZDVhOGE0ZDRkMTcwLmQxOWRkZWJmLTlhZjAtNDllZS04OGY3LTNlOWU0Y2Y3MGYyOHgxNzgyNzI0NDMxMjYyMzYzMDAw parts=10882niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete883niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Completed upload id=1884niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures885niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures886niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo887niks3-go-unit-tests> 2026/06/29 09:13:51 WARN Found objects in DB but missing from S3, will re-upload count=1888niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.36s)889niks3-go-unit-tests> === CONT  TestMetricsInventory890niks3-go-unit-tests> 2026-06-29 09:13:51.401 UTC [99849] ERROR:  relation "goose_db_version" does not exist at character 36891niks3-go-unit-tests> 2026-06-29 09:13:51.401 UTC [99849] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC892niks3-go-unit-tests> 2026-06-29 09:13:51.404 UTC [99850] ERROR:  relation "goose_db_version" does not exist at character 36893niks3-go-unit-tests> 2026-06-29 09:13:51.404 UTC [99850] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC894niks3-go-unit-tests> 2026-06-29 09:13:51.421 UTC [99851] ERROR:  relation "goose_db_version" does not exist at character 36895niks3-go-unit-tests> 2026-06-29 09:13:51.421 UTC [99851] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC896niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest897niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains898niks3-go-unit-tests> 2026-06-29 09:13:51.434 UTC [99852] ERROR:  relation "goose_db_version" does not exist at character 36899niks3-go-unit-tests> 2026-06-29 09:13:51.434 UTC [99852] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC900niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (17.81ms)901niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (357.17µs)902niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (659.04µs)903niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (33.98ms)904niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (511.71µs)905niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (3.58ms)906niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000907niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.46ms)908niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.62ms)909niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (614.21µs)910niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2911niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.41ms)912niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000913niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.07ms)914niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (207.46µs)915niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2916niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion917niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Aborted multipart uploads count=0918niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (8.28ms)919niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (10.02ms)920niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (649.33µs)921niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (878.83µs)922niks3-go-unit-tests> 2026/06/29 09:13:51 WARN Force mode enabled - objects will be deleted immediately without grace period923niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.09ms)924niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.21ms)925niks3-go-unit-tests> 2026/06/29 09:13:51 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=0926niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Vacuumed table table=pending_closures927niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Vacuumed table table=pending_objects928niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Vacuumed table table=multipart_uploads929niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Vacuumed table table=closures930niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Vacuumed table table=objects931niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.57ms)932niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000933niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.85ms)934niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000935niks3-go-unit-tests> --- PASS: TestGCMetrics (0.43s)936niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug937niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (2.47ms)938niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (2.3ms)939niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (367.71µs)940niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2941niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (515µs)942niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2943niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Created nix-cache-info in bucket bucket=bucket19944niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Created nix-cache-info in bucket bucket=bucket18945niks3-go-unit-tests> 2026-06-29 09:13:51.476 UTC [99858] ERROR:  relation "goose_db_version" does not exist at character 36946niks3-go-unit-tests> 2026-06-29 09:13:51.476 UTC [99858] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC947niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (8.83ms)948niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (379.88µs)949niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.65ms)950niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (1.82ms)951niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000952niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.01ms)953niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (211.04µs)954niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2955niks3-go-unit-tests> === NAME  TestPinProtectsFromGC956niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-99674-3193245854/TestPinProtectsFromGC43435193/001/store/flj7d5h5mgy7506wikbpnz56gf3pa362-pinned-file.txt957niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-99674-3193245854/TestPinProtectsFromGC43435193/001/store/7mvdpcsa44fhp2diqyjbjma1h2fazxd2-unpinned-file.txt958niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest959niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:960niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 20961niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 210962niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 210963niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (0.92s)964niks3-go-unit-tests> === CONT  TestGenerateLandingPage965niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)966niks3-go-unit-tests> === CONT  TestService_healthCheckHandler967niks3-go-unit-tests> 2026-06-29 09:13:51.622 UTC [99869] ERROR:  relation "goose_db_version" does not exist at character 36968niks3-go-unit-tests> 2026-06-29 09:13:51.622 UTC [99869] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC969niks3-go-unit-tests> 2026-06-29 09:13:51.637 UTC [99872] ERROR:  relation "goose_db_version" does not exist at character 36970niks3-go-unit-tests> 2026-06-29 09:13:51.637 UTC [99872] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC971niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (16.3ms)972niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (812.21µs)973niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (774.79µs)974niks3-go-unit-tests> 2026-06-29 09:13:51.654 UTC [99874] ERROR:  relation "goose_db_version" does not exist at character 36975niks3-go-unit-tests> 2026-06-29 09:13:51.654 UTC [99874] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC976niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (13.02ms)977niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000978niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (20.31ms)979niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (4.44ms)980niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (304.08µs)981niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2982niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (779.67µs)983niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (771.63µs)984niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.63s)985niks3-go-unit-tests> === CONT  TestClientMultipleUploads986niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (10.77ms)987niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 20260628120000988niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.78ms)989niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (305.5µs)990niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 2991niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures992niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (16.62ms)993niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.49s)994niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight995niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (407.21µs)996niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Starting HTTP server address=127.0.0.1:55696997niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Shutdown signal received, draining in-flight requests timeout=10s998niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (769.08µs)999niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.1ms)1000niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 202606281200001001niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.53ms)1002niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (233.25µs)1003niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 21004niks3-go-unit-tests> 2026/06/29 09:13:51 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1005niks3-go-unit-tests> 2026/06/29 09:13:51 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1006niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.40s)1007niks3-go-unit-tests> === CONT  TestClientIntegration1008niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures1009niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1010niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading flj7d5h5mgy7506wikbpnz56gf3pa362-pinned-file.txt (128B)1011niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1012niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Signed narinfos id=1 count=11013niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 narinfos1014niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1015niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Completed upload id=11016niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Upload complete. (81ms)1017niks3-go-unit-tests> === NAME  TestClientWithDependencies1018niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-99674-3193245854/TestClientWithDependencies2990322556/001/store/9qfw2fsfanjisp7z61kspkyn2g3vf88r-test-script1019niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1020niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1021niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1022niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1023niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1024niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1025niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1026niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.68s)1027niks3-go-unit-tests> === CONT  TestClientErrorHandling1028niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath1029niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath1030niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken1031niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken1032niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable1033niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable1034niks3-go-unit-tests> === CONT  TestClientCADerivations1035niks3-go-unit-tests> 2026-06-29 09:13:51.738 UTC [99885] ERROR:  relation "goose_db_version" does not exist at character 361036niks3-go-unit-tests> 2026-06-29 09:13:51.738 UTC [99885] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1037niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.07s)1038niks3-go-unit-tests> === CONT  TestCacheStatsHandler1039niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (7.94ms)1040niks3-go-unit-tests> === NAME  TestClientWithDependencies1041niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1042niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (973.75µs)1043niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.21ms)1044niks3-go-unit-tests> 2026-06-29 09:13:51.760 UTC [99892] ERROR:  relation "goose_db_version" does not exist at character 361045niks3-go-unit-tests> 2026-06-29 09:13:51.760 UTC [99892] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1046niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (10.7ms)1047niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 202606281200001048niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (891.17µs)1049niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (260µs)1050niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 21051niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.40s)1052niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload1053niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (7.09ms)1054niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (1.02ms)1055niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (1.39ms)1056niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received cleanup request method=DELETE path=/api/pending_closures1057niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (4.66ms)1058niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 202606281200001059niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.78ms)1060niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Aborted multipart uploads count=11061niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (552.17µs)1062niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 21063niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.52s)1064niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1065niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Created nix-cache-info in bucket bucket=bucket251066niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures1067niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1068niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 7mvdpcsa44fhp2diqyjbjma1h2fazxd2-unpinned-file.txt (128B)1069niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1070niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Signed narinfos id=2 count=11071niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 narinfos1072niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1073niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Completed upload id=21074niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Upload complete. (89ms)1075niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures1076niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received create pin request method=POST path=/api/pins/myapp1077niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-99674-3193245854/TestPinProtectsFromGC43435193/001/store/flj7d5h5mgy7506wikbpnz56gf3pa362-pinned-file.txt narinfo_key=flj7d5h5mgy7506wikbpnz56gf3pa362.narinfo1078niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1079niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 9qfw2fsfanjisp7z61kspkyn2g3vf88r-test-script (136B)1080niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Starting cleanup of old closures method=DELETE path=/api/closures1081niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Garbage collection started1082niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1083niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-99674-3193245854/TestNARDeduplicationMetadataUploadBug2502267413/001/store/2ip1lghvnzfvar4979kkf4h0hq3jyg80-file1.txt1084niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1085niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Signed narinfos id=1 count=11086niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 narinfos1087niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1088niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Aborted multipart uploads count=01089niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Completed upload id=11090niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Upload complete. (84ms)1091niks3-go-unit-tests> 2026/06/29 09:13:51 WARN Force mode enabled - objects will be deleted immediately without grace period1092niks3-go-unit-tests> === NAME  TestClientWithDependencies1093niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-99674-3193245854/TestClientWithDependencies2990322556/001/store) requires matching store prefix1094niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.85s)1095niks3-go-unit-tests> === CONT  TestService_Rustfstest1096niks3-go-unit-tests> 2026-06-29 09:13:51.900 UTC [99911] ERROR:  relation "goose_db_version" does not exist at character 361097niks3-go-unit-tests> 2026-06-29 09:13:51.900 UTC [99911] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1098niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (6.64ms)1099niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (631.21µs)1100niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (670.04µs)1101niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (3.3ms)1102niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 202606281200001103niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (939.79µs)1104niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (211.13µs)1105niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 21106niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.32s)1107niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists1108niks3-go-unit-tests> 2026-06-29 09:13:51.926 UTC [99915] ERROR:  relation "goose_db_version" does not exist at character 361109niks3-go-unit-tests> 2026-06-29 09:13:51.926 UTC [99915] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1110niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (6.46ms)1111niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (882.04µs)1112niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (3.13ms)1113niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20260628120000_add_object_size_and_stats.sql (2.97ms)1114niks3-go-unit-tests> 2026/06/29 09:13:51 goose: successfully migrated database to version: 202606281200001115niks3-go-unit-tests> 2026/06/29 09:13:51 OK   1_commit_pending_closure.sql (1.08ms)1116niks3-go-unit-tests> 2026/06/29 09:13:51 OK   2_object_stats_trigger.sql (206.75µs)1117niks3-go-unit-tests> 2026/06/29 09:13:51 goose: up to current file version: 21118niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Created nix-cache-info in bucket bucket=bucket271119niks3-go-unit-tests> 2026-06-29 09:13:51.968 UTC [99920] ERROR:  relation "goose_db_version" does not exist at character 361120niks3-go-unit-tests> 2026-06-29 09:13:51.968 UTC [99920] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1121niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received uploads request method=POST path=/api/pending_closures1122niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20241026095416_initial_model.sql (6.98ms)1123niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1124niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 2ip1lghvnzfvar4979kkf4h0hq3jyg80-file1.txt (160B)1125niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251210153512_drop_unused_gin_index.sql (405.54µs)1126niks3-go-unit-tests> 2026/06/29 09:13:51 OK   20251218171726_add_pins.sql (748.58µs)1127niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1128niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Signed narinfos id=1 count=11129niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Uploading 1 narinfos1130niks3-go-unit-tests> 2026/06/29 09:13:51 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1131niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (16.3ms)1132niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001133niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (899.54µs)1134niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (212.46µs)1135niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21136niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=11137niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Upload complete. (105ms)1138niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1139niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1140niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-99674-3193245854/TestNARDeduplicationMetadataUploadBug2502267413/001/store/2ip1lghvnzfvar4979kkf4h0hq3jyg80-file1.txt1141niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1142niks3-go-unit-tests>         Compression: zstd1143niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1144niks3-go-unit-tests>         NarSize: 1601145niks3-go-unit-tests>         References: 1146niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1147niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1148niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1149niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1150niks3-go-unit-tests> === NAME  TestClientMultipleUploads1151niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-99674-3193245854/TestClientMultipleUploads1521977442/001/store/ssp702vrhsghdx3kfdbry8i9bfldxc1v-test-file-0.txt1152niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Created nix-cache-info in bucket bucket=bucket281153niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1154niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-99674-3193245854/TestNARDeduplicationMetadataUploadBug2502267413/001/store/6d1sf7b870wvwvaz5kasccc2asjk68cd-file2.txt1155niks3-go-unit-tests> 2026-06-29 09:13:52.075 UTC [99930] ERROR:  relation "goose_db_version" does not exist at character 361156niks3-go-unit-tests> 2026-06-29 09:13:52.075 UTC [99930] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1157niks3-go-unit-tests> === NAME  TestClientIntegration1158niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-99674-3193245854/TestClientIntegration345971729/002/store/df8gbh2awcyxppnys89xk0rnj3bk5h5a-test-file.txt1159niks3-go-unit-tests> === NAME  TestClientMultipleUploads1160niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-99674-3193245854/TestClientMultipleUploads1521977442/001/store/3rgvivggf6ak9nqwax3bnqysmnjmghj6-test-file-1.txt1161niks3-go-unit-tests> 2026-06-29 09:13:52.083 UTC [99934] ERROR:  relation "goose_db_version" does not exist at character 361162niks3-go-unit-tests> 2026-06-29 09:13:52.083 UTC [99934] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1163niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (7.62ms)1164niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (8.19ms)1165niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (538.54µs)1166niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (719.92µs)1167niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (4.34ms)1168niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (4.54ms)1169niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.38ms)1170niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001171niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.72ms)1172niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001173niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (2.15ms)1174niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (2.08ms)1175niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (354.08µs)1176niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21177niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (447.58µs)1178niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21179niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Created nix-cache-info in bucket bucket=bucket301180niks3-go-unit-tests> 2026-06-29 09:13:52.116 UTC [99939] ERROR:  relation "goose_db_version" does not exist at character 361181niks3-go-unit-tests> 2026-06-29 09:13:52.116 UTC [99939] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1182niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-99674-3193245854/TestClientMultipleUploads1521977442/001/store/rcxw1pll77w825laa2cq8q1cyhv9i9qx-test-file-2.txt1183niks3-go-unit-tests> 2026-06-29 09:13:52.132 UTC [99944] ERROR:  relation "goose_db_version" does not exist at character 361184niks3-go-unit-tests> 2026-06-29 09:13:52.132 UTC [99944] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1185niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.39s)1186niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler1187niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (9.5ms)1188niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (5.23ms)1189niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (2.41ms)1190niks3-go-unit-tests> 2026/06/29 09:13:52 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=01191niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_closures1192niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (22.34ms)1193niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (10.44ms)1194niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001195niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (686.46µs)1196niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_objects1197niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.91ms)1198niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=multipart_uploads1199niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (635.88µs)1200niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21201niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (2.47ms)1202niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=closures1203niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=objects1204niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1205niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (3.28ms)1206niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001207niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.26ms)1208niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (525.71µs)1209niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21210niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1211niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1212niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1213niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1214niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1215niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=2 count=11216niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 1 narinfos1217niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1218niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=21219niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Upload complete. (95ms)1220niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1221niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1222niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-99674-3193245854/TestNARDeduplicationMetadataUploadBug2502267413/001/store/6d1sf7b870wvwvaz5kasccc2asjk68cd-file2.txt1223niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1224niks3-go-unit-tests>         Compression: zstd1225niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1226niks3-go-unit-tests>         NarSize: 1601227niks3-go-unit-tests>         References: 1228niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1229niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1230niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1231niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1232niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.73s)1233niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1234niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1235niks3-go-unit-tests> 2026-06-29 09:13:52.209 UTC [99960] ERROR:  relation "goose_db_version" does not exist at character 361236niks3-go-unit-tests> 2026-06-29 09:13:52.209 UTC [99960] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1237niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1238niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1239niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading df8gbh2awcyxppnys89xk0rnj3bk5h5a-test-file.txt (152B)1240niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1241niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=1 count=11242niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 1 narinfos1243niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1244niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=11245niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Upload complete. (128ms)1246niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (29.35ms)1247niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (655.04µs)1248niks3-go-unit-tests> === NAME  TestClientIntegration1249niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1250niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-99674-3193245854/TestClientIntegration345971729/002/store/df8gbh2awcyxppnys89xk0rnj3bk5h5a-test-file.txt1251niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1252niks3-go-unit-tests>         Compression: zstd1253niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11254niks3-go-unit-tests>         NarSize: 1521255niks3-go-unit-tests>         References: 1256niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11257niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (955.42µs)1258niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1259niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1260niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1261niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1262niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1263niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (1.2ms)1264niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001265niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.26ms)1266niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1267niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (682.38µs)1268niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21269niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1270niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1271niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 3rgvivggf6ak9nqwax3bnqysmnjmghj6-test-file-1.txt (160B)1272niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading ssp702vrhsghdx3kfdbry8i9bfldxc1v-test-file-0.txt (160B)1273niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading rcxw1pll77w825laa2cq8q1cyhv9i9qx-test-file-2.txt (160B)1274niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.37s)1275niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1276niks3-go-unit-tests> 2026/06/29 09:13:52 INFO OIDC provider initialized name=test1277niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1278niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=3 count=11279niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1280niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=1 count=11281niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1282niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=2 count=11283niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 3 narinfos1284niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1285niks3-go-unit-tests> 2026-06-29 09:13:52.260 UTC [99966] ERROR:  relation "goose_db_version" does not exist at character 361286niks3-go-unit-tests> 2026-06-29 09:13:52.260 UTC [99966] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1287niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Starting cleanup of old closures method=DELETE path=/api/closures1288niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Garbage collection started1289niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Aborted multipart uploads count=01290niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=11291niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1292niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=21293niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1294niks3-go-unit-tests> 2026/06/29 09:13:52 WARN Force mode enabled - objects will be deleted immediately without grace period1295niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=31296niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Upload complete. (124ms)1297niks3-go-unit-tests> === NAME  TestClientMultipleUploads1298niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 160.970459ms1299niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.62s)1300niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1301niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (8.44ms)1302niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (1.06ms)1303niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (4.68ms)1304niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (4.37ms)1305niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001306niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.61ms)1307niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (545.33µs)1308niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21309niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1310niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1311niks3-go-unit-tests> {"timestamp":"2026-06-29T09:13:52.316961Z","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(9)"}1312niks3-go-unit-tests> {"timestamp":"2026-06-29T09:13:52.316979Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket34, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(9)"}1313niks3-go-unit-tests> 2026/06/29 09:13:52 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=ZDYyOTYzMDYtNDAyOS00YWJiLWE5M2UtZDVhOGE0ZDRkMTcwLjhhOWIxMTlhLWMyMTUtNDQ0Ni05NDFhLWExNTdmYmMxNzRmYXgxNzgyNzI0NDMyMzExNzkwMDAw1314niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZDYyOTYzMDYtNDAyOS00YWJiLWE5M2UtZDVhOGE0ZDRkMTcwLjhhOWIxMTlhLWMyMTUtNDQ0Ni05NDFhLWExNTdmYmMxNzRmYXgxNzgyNzI0NDMyMzExNzkwMDAw parts=11315niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.40s)1316niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest1317niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1318niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZDYyOTYzMDYtNDAyOS00YWJiLWE5M2UtZDVhOGE0ZDRkMTcwLjNjOGViMmU0LTJlYzQtNGE2MC04ZTQ2LTVkZGVkYTZmYWMzYXgxNzgyNzI0NDMyMTc1ODc5MDAw parts=121319niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.55s)1320niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1321niks3-go-unit-tests> === NAME  TestClientCADerivations1322niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-99674-3193245854/TestClientCADerivations3746643189/001/store/3z69lmfyc2cbn19lmw2ry5470b0iljkw-ca-test1323niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1324niks3-go-unit-tests> 2026-06-29 09:13:52.427 UTC [99981] ERROR:  relation "goose_db_version" does not exist at character 361325niks3-go-unit-tests> 2026-06-29 09:13:52.427 UTC [99981] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1326niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (7.96ms)1327niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (977µs)1328niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (1.52ms)1329niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.36ms)1330niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001331niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.36ms)1332niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (257µs)1333niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21334niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1335niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1336niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1337niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1338niks3-go-unit-tests> 2026-06-29 09:13:52.492 UTC [99986] ERROR:  relation "goose_db_version" does not exist at character 361339niks3-go-unit-tests> 2026-06-29 09:13:52.492 UTC [99986] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1340niks3-go-unit-tests> 2026-06-29 09:13:52.492 UTC [99987] ERROR:  relation "goose_db_version" does not exist at character 361341niks3-go-unit-tests> 2026-06-29 09:13:52.492 UTC [99987] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1342niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1343niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 3z69lmfyc2cbn19lmw2ry5470b0iljkw-ca-test (144B)1344niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1345niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Signed narinfos id=1 count=11346niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Uploading 1 narinfos1347niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1348niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (9.65ms)1349niks3-go-unit-tests> 2026/06/29 09:13:52 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=01350niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (10.33ms)1351niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=11352niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Upload complete. (142ms)1353niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (851.42µs)1354niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (867.63µs)1355niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-99674-3193245854/TestClientCADerivations3746643189/001/store/3z69lmfyc2cbn19lmw2ry5470b0iljkw-ca-test1356niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1357niks3-go-unit-tests>         Compression: zstd1358niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1359niks3-go-unit-tests>         NarSize: 1441360niks3-go-unit-tests>         References: 1361niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-99674-3193245854/TestClientCADerivations3746643189/001/store/bsqxjn49jd7pmqp7a16wazva01i96wyr-ca-test.drv1362niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1363niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1364niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1365niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1366niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1367niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (4.26ms)1368niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZDYyOTYzMDYtNDAyOS00YWJiLWE5M2UtZDVhOGE0ZDRkMTcwLjM3YzQ3ZjI1LWI4YjYtNGI3MC04YjE4LWExMjY2MTM0OTA5ZngxNzgyNzI0NDMyNDU0MjE1MDAw parts=101369niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1370niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_closures1371niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (8.94ms)1372niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (5.45ms)1373niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001374niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Completed upload id=11375niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001376niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_objects1377niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/api/pending_closures1378niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=multipart_uploads1379niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Starting cleanup of old closures method=DELETE path=/api/closures1380niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.19ms)1381niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=closures1382niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (309.75µs)1383niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21384niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.2ms)1385niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001386niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=objects1387niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.09ms)1388niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (210.71µs)1389niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21390niks3-go-unit-tests> 2026/06/29 09:13:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1391niks3-go-unit-tests> 2026/06/29 09:13:52 WARN mTLS auth: bound subjects configured but subject DN unavailable1392niks3-go-unit-tests> 2026/06/29 09:13:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1393niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.37s)1394niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML1395niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Aborted multipart uploads count=01396niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1397niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1398niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1399niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1400niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1401niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1402niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1403niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1404niks3-go-unit-tests> === CONT  TestReadProxyDisabled1405niks3-go-unit-tests> 2026/06/29 09:13:52 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=01406niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_closures1407niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=pending_objects1408niks3-go-unit-tests> 2026-06-29 09:13:52.573 UTC [99993] ERROR:  relation "goose_db_version" does not exist at character 361409niks3-go-unit-tests> 2026-06-29 09:13:52.573 UTC [99993] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1410niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=multipart_uploads1411niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=closures1412niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Vacuumed table table=objects1413niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (6.02ms)1414niks3-go-unit-tests> === NAME  TestClientCADerivations1415niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket30?endpoint=http://localhost:55586&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-99674-3193245854/TestClientCADerivations3746643189/001/store'1416niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (409µs)1417niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11418niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (812.42µs)1419niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.86s)1420niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1421niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1422niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1423niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1424niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1425niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1426niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1427niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1428niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1429niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1430niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1431niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1432niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1433niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1434niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1435niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1436niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1437niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1438niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1439niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1440niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1441niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1442niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1443niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1444niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1445niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1446niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21447niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1448niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1449niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1450niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1451niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1452niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1453niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1454niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1455niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1456niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1457niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1458niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1459niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1460niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1461niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1462niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1463niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1464niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1465niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1466niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1467niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1468niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1469niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1470niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1471niks3-go-unit-tests> === CONT  TestParseSingleRange/none1472niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1473niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1474niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1475niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1476niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1477niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1478niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1479niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1480niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1481niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1482niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1483niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1484niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1485niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1486niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1487niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1488niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1489niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1490niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1491niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1492niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1493niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1494niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1495niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1496niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1497niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1498niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1499niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1500niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/1501niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1502niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/1503niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1504niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/1505niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1506niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received request for more parts method=POST path=/1507niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1508niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1509niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1510niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1511niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1512niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1513niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received uploads request method=POST path=/1514niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.25ms)1515niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001516niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (886.38µs)1517niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (200.71µs)1518niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21519niks3-go-unit-tests> 2026/06/29 09:13:52 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1520niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.31s)1521niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1522niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received request for more parts method=POST path=/1523niks3-go-unit-tests> 2026-06-29 09:13:52.605 UTC [99996] ERROR:  relation "goose_db_version" does not exist at character 361524niks3-go-unit-tests> 2026-06-29 09:13:52.605 UTC [99996] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1525niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1526niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received complete multipart upload request method=POST path=/1527niks3-go-unit-tests> 2026/06/29 09:13:52 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001528niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.47s)1529niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1530niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1531niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1532niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1533niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1534niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1535niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1536niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1537niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1538niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1539niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1540niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1541niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1542niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1543niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1544niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1545niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1546niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1547niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1548niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1549niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1550niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1551niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1552niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1553niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1554niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1555niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1556niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1557niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1558niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1559niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1560niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1561niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1562niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1563niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1564niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1565niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1566niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1567niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1568niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1569niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1570niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1571niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1572niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1573niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1574niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1575niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1576niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1577niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1578niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1579niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1580niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)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  TestClientErrorHandling/InvalidStorePath1591niks3-go-unit-tests> 2026-06-29 09:13:52.622 UTC [99998] ERROR:  relation "goose_db_version" does not exist at character 361592niks3-go-unit-tests> 2026-06-29 09:13:52.622 UTC [99998] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1593niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1594niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (13.65ms)1595niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (386.21µs)1596niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (955.83µs)1597niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (1.84ms)1598niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001599niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.47ms)1600niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (268.5µs)1601niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21602niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (6.51ms)1603niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (561.25µs)1604niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.31s)1605niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1606niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (1.25ms)1607niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (2.8ms)1608niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001609niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (829.92µs)1610niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (350.04µs)1611niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21612niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.34s)1613niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1614niks3-go-unit-tests> 2026/06/29 09:13:52 INFO OIDC auth successful provider=test1615niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1616niks3-go-unit-tests> 2026/06/29 09:13:52 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]1617niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1618niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1619niks3-go-unit-tests> 2026/06/29 09:13:52 WARN Authentication failed token_preview=eyJhbGciOi...llQVqzDBcA 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]1620niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.32s)1621niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1622niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1623niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1624niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1625niks3-go-unit-tests> 2026/06/29 09:13:52 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1626niks3-go-unit-tests> 2026-06-29 09:13:52.812 UTC [111] ERROR:  relation "goose_db_version" does not exist at character 361627niks3-go-unit-tests> 2026-06-29 09:13:52.812 UTC [111] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1628niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (6.17ms)1629niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (363.79µs)1630niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (710.42µs)1631niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (1.15ms)1632niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001633niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (932.42µs)1634niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (197µs)1635niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21636niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.28s)1637niks3-go-unit-tests> 2026-06-29 09:13:52.841 UTC [112] ERROR:  relation "goose_db_version" does not exist at character 361638niks3-go-unit-tests> 2026-06-29 09:13:52.841 UTC [112] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1639niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (5.39ms)1640niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (349.13µs)1641niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (758.75µs)1642niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (1.32ms)1643niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001644niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (1.09ms)1645niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (255.58µs)1646niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21647niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.29s)1648niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.03s)1649niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1650niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1651niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.27s)1652niks3-go-unit-tests> 2026-06-29 09:13:52.864 UTC [113] ERROR:  relation "goose_db_version" does not exist at character 361653niks3-go-unit-tests> 2026-06-29 09:13:52.864 UTC [113] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1654niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (3.4ms)1655niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (360.92µs)1656niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (674.96µs)1657niks3-go-unit-tests> 2026-06-29 09:13:52.883 UTC [114] ERROR:  relation "goose_db_version" does not exist at character 361658niks3-go-unit-tests> 2026-06-29 09:13:52.883 UTC [114] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1659niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (9.72ms)1660niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001661niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (846.17µs)1662niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (203.33µs)1663niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21664niks3-go-unit-tests> 2026/06/29 09:13:52 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=192.290154ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1665niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20241026095416_initial_model.sql (3.55ms)1666niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251210153512_drop_unused_gin_index.sql (347.5µs)1667niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20251218171726_add_pins.sql (627.96µs)1668niks3-go-unit-tests> 2026/06/29 09:13:52 OK   20260628120000_add_object_size_and_stats.sql (1.22ms)1669niks3-go-unit-tests> 2026/06/29 09:13:52 goose: successfully migrated database to version: 202606281200001670niks3-go-unit-tests> 2026/06/29 09:13:52 OK   1_commit_pending_closure.sql (733.5µs)1671niks3-go-unit-tests> 2026/06/29 09:13:52 OK   2_object_stats_trigger.sql (187.04µs)1672niks3-go-unit-tests> 2026/06/29 09:13:52 goose: up to current file version: 21673niks3-go-unit-tests> 2026/06/29 09:13:53 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1674niks3-go-unit-tests> 2026/06/29 09:13:53 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=360.66296ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1675niks3-go-unit-tests> 2026/06/29 09:13:53 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=876.806705ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1676niks3-go-unit-tests> 2026/06/29 09:13:53 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01677niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1678niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1679niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.83s)1680niks3-go-unit-tests> 2026/06/29 09:13:54 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.60s)1685niks3-go-unit-tests> 2026/06/29 09:13:54 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.703323303s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1686niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1687niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.30s)1688niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.40s)1689niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.42s)1690niks3-go-unit-tests> 2026/06/29 09:13:56 WARN Rate limiter enabled after throttle name=s3-test rate=51691niks3-go-unit-tests> 2026/06/29 09:13:56 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1692niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1693niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101694niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001695niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.73s)1696niks3-go-unit-tests> PASS1697niks3-go-unit-tests> {"timestamp":"2026-06-29T09:13:57.025922Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:55679"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}1698niks3-go-unit-tests> 2026-06-29 09:13:57.088 UTC [99712] LOG:  received smart shutdown request1699niks3-go-unit-tests> 2026-06-29 09:13:57.089 UTC [99712] LOG:  background worker "logical replication launcher" (PID 99719) exited with exit code 11700niks3-go-unit-tests> 2026-06-29 09:13:57.099 UTC [99714] LOG:  shutting down1701niks3-go-unit-tests> 2026-06-29 09:13:57.099 UTC [99714] LOG:  checkpoint starting: shutdown immediate1702niks3-go-unit-tests> 2026-06-29 09:13:58.111 UTC [99714] LOG:  checkpoint complete: wrote 13142 buffers (80.2%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.741 s, sync=0.270 s, total=1.013 s; sync files=14421, longest=0.025 s, average=0.001 s; distance=197508 kB, estimate=197508 kB; lsn=0/D5F4040, redo lsn=0/D5F40401703niks3-go-unit-tests> 2026-06-29 09:13:58.114 UTC [99712] LOG:  database system is shut down1704niks3-go-unit-tests> Running OIDC tests...1705niks3-go-unit-tests> === RUN   TestGlobMatch1706niks3-go-unit-tests> === PAUSE TestGlobMatch1707niks3-go-unit-tests> === RUN   TestAudienceForIssuer1708niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1709niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1710niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1711niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1712niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1713niks3-go-unit-tests> === RUN   TestValidateToken_Expired1714niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1715niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1716niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1717niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1718niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1719niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1720niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1721niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1722niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1723niks3-go-unit-tests> === CONT  TestGlobMatch1724niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1725niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1726niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1727niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1728niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1729niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1730niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1731niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1732niks3-go-unit-tests> === CONT  TestAudienceForIssuer1733niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1734niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1735niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1736niks3-go-unit-tests> === CONT  TestValidateToken_Expired1737niks3-go-unit-tests> === RUN   TestGlobMatch/*_1738niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1739niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1740niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1741niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1742niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1743niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1744niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1745niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1746niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1747niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1748niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1749niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1750niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1751niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1752niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1753niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1754niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1755niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1756niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1757niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1758niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1759niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1760niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1761niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1762niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1763niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1764niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1765niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01766niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01767niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1768niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1769niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1770niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1771niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1772niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1773niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1774niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1775niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1776niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1777niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1778niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1779niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1780niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1781niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1782niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1783niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1784niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1785niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1786niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1787niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1788niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1789niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1790niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1791niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1792niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1793niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1794niks3-go-unit-tests> === CONT  TestGlobMatch/*_1795niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1796niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1797niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1798niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1799niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1800niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1801niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1802niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1803niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01804niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1805niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1806niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1807niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1808niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1809niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1810niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1811niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1812niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1813niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1814niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1815niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1816niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1834niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=provider21835niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=test1836niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=test1837niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=provider11838niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=test1839niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=test1840niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=test1841niks3-go-unit-tests> 2026/06/29 09:13:58 INFO OIDC provider initialized name=provider11842niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1843niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1844niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1845niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1846niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1847niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1848niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1849niks3-go-unit-tests> PASS1850niks3-go-unit-tests> Running hook tests...1851niks3-go-unit-tests> === RUN   TestSendPathsEmpty1852niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1853niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1854niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1855niks3-go-unit-tests> === RUN   TestQueueDeduplication1856niks3-go-unit-tests> === PAUSE TestQueueDeduplication1857niks3-go-unit-tests> === RUN   TestQueueRemove1858niks3-go-unit-tests> === PAUSE TestQueueRemove1859niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1860niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1861niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1862niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1863niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1864niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1865niks3-go-unit-tests> === RUN   TestServerClientIntegration1866niks3-go-unit-tests> === PAUSE TestServerClientIntegration1867niks3-go-unit-tests> === RUN   TestServerQueueError1868niks3-go-unit-tests> === PAUSE TestServerQueueError1869niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1870niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1871niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1872niks3-go-unit-tests>         PASS1873niks3-go-unit-tests>         1874niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1875niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1876niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1877niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1878niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1879niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1880niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1881niks3-go-unit-tests> === CONT  TestSendPathsEmpty1882niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1883niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1884niks3-go-unit-tests> === CONT  TestQueueDeduplication1885niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1886niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1887niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1888niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1889niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1890niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1891niks3-go-unit-tests> === CONT  TestServerQueueError1892niks3-go-unit-tests> === CONT  TestServerClientIntegration1893niks3-go-unit-tests> 2026/06/29 09:13:59 ERROR Failed to queue paths error="permission denied" count=11894niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1895niks3-go-unit-tests> === CONT  TestQueueRemove1896niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1897niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1898niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1899niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Upload queue status pending=21900niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Upload queue status pending=21901niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Uploading batch count=21902niks3-go-unit-tests> 2026/06/29 09:13:59 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-99674-3193245854/TestWorkerSkipsGCdPaths3763479485/002/nonexistent1903niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Uploading batch count=11904niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1905niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Upload queue status pending=21906niks3-go-unit-tests> 2026/06/29 09:13:59 INFO Uploading batch count=11907niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1908niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1909niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1910niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1911niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1912niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)1913niks3-go-unit-tests> PASS19141915post-build step Upload coverage to codecov: ok1916Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests