nixbot

1this derivation will be built:2  /nix/store/azl6f3iw15qwdms14jx8xijfwjz9ck3g-niks3-go-unit-tests.drv3building '/nix/store/azl6f3iw15qwdms14jx8xijfwjz9ck3g-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/azl6f3iw15qwdms14jx8xijfwjz9ck3g-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  TestConvertHashToNix3276niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall77niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility78niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3279niks3-go-unit-tests> === CONT  TestFileTokenMissing80niks3-go-unit-tests> === CONT  TestResolveStorePath81niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails82niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON83niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken84niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh85niks3-go-unit-tests> === CONT  TestSetClientTLS86niks3-go-unit-tests> === CONT  TestDumpPathSingleFile87niks3-go-unit-tests> === CONT  TestShellSplitErrors88niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)89niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash90niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)91niks3-go-unit-tests> === CONT  TestShellSplit92niks3-go-unit-tests> === CONT  TestEncodeNixBase3293niks3-go-unit-tests> === CONT  TestDumpPathWriterError94niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash95niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody96niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash97niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input98niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input99niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash100niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input101niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix102niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter enabled after throttle name=server-test rate=5103niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36543104niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter backed off name=server-test rate=5105niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36543106niks3-go-unit-tests> === CONT  TestFileTokenEmpty107niks3-go-unit-tests> === CONT  TestPartSizeForNAR108niks3-go-unit-tests> === CONT  TestRateLimiterFeedback109niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess110niks3-go-unit-tests> === CONT  TestStaticToken111niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches112niks3-go-unit-tests> === CONT  TestSetClientTLSErrors113niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility114niks3-go-unit-tests> === CONT  TestParsePathInfoJSON115niks3-go-unit-tests> === CONT  TestCaseHackSuffix116niks3-go-unit-tests> === CONT  TestGetStorePathHash117niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport118niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer119niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand120niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths121niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32122niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field123niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)124niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum125niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)126niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)127niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)128niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.00s)129niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.00s)130niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)131niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)132niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format133niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum134niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths135niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum136niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths137niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum138niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format139niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum140niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path141niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format142niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field143niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format144niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text145niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths146niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format147niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format148niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32149niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter enabled after throttle name=server-test rate=5150niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum151niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path152niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists153niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error154niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text155niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)156niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.02s)157niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)158niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)159niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)160niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive161niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)162niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)163niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts164niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists165niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error166niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format167niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing168niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format169niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing170niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)171niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive172niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter173niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter174niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts175niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter176niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths177niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)178niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths179niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert180niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert181niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error182niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA183niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error184niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists185niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing186niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)187niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)188niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)189niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)190niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text191niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text192niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method193niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method194niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field195niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text196niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive197niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format198niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter199niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths200niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file201niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon202niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file203niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file204niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format205niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file206niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon207niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error208niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)209niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text210niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.02s)211niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI212niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method213niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB214niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA215niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input216niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file217niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error218niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)219niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)220niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)221niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB222niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter223niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input224niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object225niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path226niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter227niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport228niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error229niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error230niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error231niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file232niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.02s)233niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)234niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)235niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)236niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)237niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)238niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI239niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only240niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only241niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object242niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512243niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB244niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512245niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport246niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter247niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file248niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.01s)249niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)250niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)251niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)252niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)253niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB254niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI255niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB256niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert257niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport258niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA259niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)260niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512261niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon262niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.01s)263niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)264niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)265niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)266niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)267niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter268niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter269niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)270niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)271niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)272niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter273niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum274niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts275niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB276niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum277niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum278niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object279niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.03s)280niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON281niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON282niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file283niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON284niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.02s)285niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)286niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)287niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)288niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)289niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)290niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)291niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)292niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter293niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter294niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format295niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only296niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input297niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format298niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file299niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file300niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.01s)301niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)302niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)303niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)304niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)305niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)306niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter enabled after throttle name=server-test rate=5307niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file308niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:34313309niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file310niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter enabled after throttle name=server-test rate=5311niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:44899312niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter backed off name=server-test rate=5313niks3-go-unit-tests> 2026/06/28 14:39:21 WARN Rate limiter backed off name=server-test rate=5314niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)315niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)316niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)317niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)318niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)319niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)320niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)321niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)322niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)323niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)324niks3-go-unit-tests> 2026/06/28 14:39:21 http: TLS handshake error from 127.0.0.1:51118: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.03s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.04s)330niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.04s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.06s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.10s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres1714448985/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /build/postgres1714448985/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres1714448985:5432 - no response364niks3-go-unit-tests> 2026-06-28 14:39:23.533 UTC [303] LOG:  starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 14:39:23.534 UTC [303] LOG:  listening on Unix socket "/build/postgres1714448985/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 14:39:23.537 UTC [307] LOG:  database system was shut down at 2026-06-28 14:39:23 UTC367niks3-go-unit-tests> 2026-06-28 14:39:23.541 UTC [303] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres1714448985:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:23.910825386Z","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(767)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (1083) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun401niks3-go-unit-tests> 2026-06-28 14:39:24.007 UTC [1101] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-28 14:39:24.007 UTC [1101] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (14.45ms)404niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (1.44ms)405niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (2.89ms)406niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (2.85ms)407niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (1.93ms)409niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (933.22µs)410niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.11s)412niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences413niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences414niks3-go-unit-tests> === RUN   TestGCMetrics415niks3-go-unit-tests> === PAUSE TestGCMetrics416niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew417niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew418niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams419niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams420niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams421niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams422niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty423niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty424niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest425niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest426niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask427niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask428niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates429niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates430niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail431niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail432niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight433niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight434niks3-go-unit-tests> === RUN   TestService_healthCheckHandler435niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler436niks3-go-unit-tests> === RUN   TestGenerateLandingPage437niks3-go-unit-tests> === PAUSE TestGenerateLandingPage438niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug439niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug440niks3-go-unit-tests> === RUN   TestMetricsInventory441niks3-go-unit-tests> === PAUSE TestMetricsInventory442niks3-go-unit-tests> === RUN   TestService_NativeMTLS443niks3-go-unit-tests> === PAUSE TestService_NativeMTLS444niks3-go-unit-tests> === RUN   TestServerTLSConfig445niks3-go-unit-tests> === PAUSE TestServerTLSConfig446niks3-go-unit-tests> === RUN   TestMultipartCleanup447niks3-go-unit-tests> === PAUSE TestMultipartCleanup448niks3-go-unit-tests> === RUN   TestObjectStatsTrigger449niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger450niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC451niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC452niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest453niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest454niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted455niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted456niks3-go-unit-tests> === RUN   TestParseSingleRange457niks3-go-unit-tests> === PAUSE TestParseSingleRange458niks3-go-unit-tests> === RUN   TestIsValidCachePath459niks3-go-unit-tests> === PAUSE TestIsValidCachePath460niks3-go-unit-tests> === RUN   TestReadProxyNarinfo461niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo462niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed463niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed464niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming465niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming466niks3-go-unit-tests> === RUN   TestReadProxy404467niks3-go-unit-tests> === PAUSE TestReadProxy404468niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath469niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath470niks3-go-unit-tests> === RUN   TestReadProxyHead471niks3-go-unit-tests> === PAUSE TestReadProxyHead472niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet473niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet474niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML475niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML476niks3-go-unit-tests> === RUN   TestReadProxyDisabled477niks3-go-unit-tests> === PAUSE TestReadProxyDisabled478niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest479niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest480niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload481niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload482niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists483niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists484niks3-go-unit-tests> === RUN   TestService_Rustfstest485niks3-go-unit-tests> === PAUSE TestService_Rustfstest486niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated487niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)488niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy489niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)490niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy491niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/28 14:39:24 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"501niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)502niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle503niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle504niks3-go-unit-tests> === RUN   TestProxyWriteTimeout505niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout506niks3-go-unit-tests> === RUN   TestIsValidUploadKey507niks3-go-unit-tests> === PAUSE TestIsValidUploadKey508niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys509niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys510niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody511niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody512niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler513niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler514niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler515niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler516niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity517niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity518niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered519niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered520niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT521niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT522niks3-go-unit-tests> === CONT  TestService_AuthMiddleware523niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet524niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail525niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)526niks3-go-unit-tests> === CONT  TestReadProxyHead527niks3-go-unit-tests> === CONT  TestClientWithDependencies528niks3-go-unit-tests> === CONT  TestIsValidUploadKey529niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo530niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo531niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists532niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences533niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst534niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst535niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT536niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered537niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity538niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler539niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler540niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody541niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys542niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams543niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info544niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info545niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates546niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal547niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal548niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask549niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest550niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty551niks3-go-unit-tests> === CONT  TestCacheStatsHandler552niks3-go-unit-tests> === CONT  TestClientMultipleUploads553niks3-go-unit-tests> === CONT  TestClientIntegration554niks3-go-unit-tests> === CONT  TestClientErrorHandling555niks3-go-unit-tests> === CONT  TestClientCADerivations556niks3-go-unit-tests> === CONT  TestGCMetrics557niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams558niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew559niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware560niks3-go-unit-tests> === CONT  TestCacheConfigHandler561niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC562niks3-go-unit-tests> === CONT  TestService_Rustfstest563niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle564niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest565niks3-go-unit-tests> === CONT  TestPinProtectsFromGC566niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload567niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC568niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects569niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath570niks3-go-unit-tests> === CONT  TestReadProxy404571niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming572niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed573niks3-go-unit-tests> === CONT  TestReadProxyNarinfo574niks3-go-unit-tests> === CONT  TestIsValidCachePath575niks3-go-unit-tests> === CONT  TestParseSingleRange576niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted577niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader578niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest579niks3-go-unit-tests> === CONT  TestMetricsInventory580niks3-go-unit-tests> === CONT  TestGenerateLandingPage581niks3-go-unit-tests> === CONT  TestObjectStatsTrigger582niks3-go-unit-tests> === CONT  TestMultipartCleanup583niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug584niks3-go-unit-tests> === CONT  TestServerTLSConfig585niks3-go-unit-tests> === CONT  TestService_healthCheckHandler586niks3-go-unit-tests> === CONT  TestService_NativeMTLS587niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight588niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML589niks3-go-unit-tests> === CONT  TestProxyWriteTimeout590niks3-go-unit-tests> === CONT  TestReadProxyDisabled591niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz592niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)593niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)594niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)595niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)596niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)597niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)598niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo599niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo600niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar601niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key602niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath603niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath604niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken605niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken606niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable607niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable608niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath609niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)610niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable611niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer612niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer613niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured614niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured615niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys616niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys617niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator618niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo619niks3-go-unit-tests> === RUN   TestParseSingleRange/none620niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo621niks3-go-unit-tests> === PAUSE TestParseSingleRange/none622niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA623niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit624niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars625niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars626niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit627niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst628niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst629niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored630niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz631niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz632niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2633niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2634niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed635niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed636niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls637niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls638niks3-go-unit-tests> === RUN   TestIsValidCachePath/log639niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log640niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation641niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation642niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info643niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info644niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html645niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html646niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent647niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator648niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer649niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys650niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator651niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken652niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured653niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar654niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz655niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key656niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA657niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent658niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file659niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file660niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain661niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file662niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar663niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file664niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar665niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA666niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file667niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored668niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file669niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle670niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size671niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain672niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size673niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo674niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key675niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key676niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar677niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar678niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash679niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash680niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key681niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal682niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info683niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key684niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle685niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size686niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e687niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e688niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u689niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u690niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path691niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing692niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received request for more parts method=POST path=/693niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty694niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete multipart upload request method=POST path=/695niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/696niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/697niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path698niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty699niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)700niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)701niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)702niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)703niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)704niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing705niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty706niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)707niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)708niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)709niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)710niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)711niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty712niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash713niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash714niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log715niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start716niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension717niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start718niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log719niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension720niks3-go-unit-tests> === RUN   TestParseSingleRange/closed721niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed722niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file723niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended724niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended725niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash726niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)727niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)728niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)729niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)730niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)731niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file732niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size733niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash734niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size735niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path736niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle737niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation738niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash739niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls740niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent741niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name742niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html743niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)744niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)745niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)746niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)747niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e748niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo749niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash750niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2751niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz752niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed753niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst754niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info755niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix756niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)757niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix758niks3-go-unit-tests> === CONT  TestIsValidCachePath/log759niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty760niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size761niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size762niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte763niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name764niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension765niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark766niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark767niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals768niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals769niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u770niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation771niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation772niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars773niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output774niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)775niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)776niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)777niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)778niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)779niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)780niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)781niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)782niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)783niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)784niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)785niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)786niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)787niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)788niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)789niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)790niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)791niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)792niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)793niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)794niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)795niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output796niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info797niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte798niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info799niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html800niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF801niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html802niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF803niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type804niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF805niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type806niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF807niks3-go-unit-tests> === CONT  TestParseSingleRange/none808niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type809niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF810niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size811niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored812niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended813niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF814niks3-go-unit-tests> === CONT  TestParseSingleRange/closed815niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start816niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type817niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty818niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash819niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit820niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte821niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix822niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size823niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)824niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)825niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)826niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)827niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)828niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)829niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)830niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)831niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)832niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)833niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)834niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)835niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)836niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)837niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)838niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type839niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC provider initialized name=test840niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type841niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal842niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal843niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar844niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar845niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute846niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute847niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key848niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key849niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type850niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type851niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo852niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type853niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar854niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal855niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info856niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type857niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file858niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals859niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark860niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name861niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type862niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key863niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute864niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type865niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html866niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log867niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing868niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain869niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz870niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output871niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation872niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst873niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.01s)874niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)875niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)876niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)877niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)878niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)896niks3-go-unit-tests> 2026-06-28 14:39:24.397 UTC [1254] ERROR:  relation "goose_db_version" does not exist at character 36897niks3-go-unit-tests> 2026-06-28 14:39:24.397 UTC [1254] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC898niks3-go-unit-tests> 2026-06-28 14:39:24.398 UTC [1251] ERROR:  relation "goose_db_version" does not exist at character 36899niks3-go-unit-tests> 2026-06-28 14:39:24.398 UTC [1251] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC900niks3-go-unit-tests> 2026-06-28 14:39:24.400 UTC [1253] ERROR:  relation "goose_db_version" does not exist at character 36901niks3-go-unit-tests> 2026-06-28 14:39:24.400 UTC [1253] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC902niks3-go-unit-tests> 2026-06-28 14:39:24.410 UTC [1233] ERROR:  relation "goose_db_version" does not exist at character 36903niks3-go-unit-tests> 2026-06-28 14:39:24.410 UTC [1233] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC904niks3-go-unit-tests> 2026/06/28 14:39:24 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_closures905niks3-go-unit-tests> 2026-06-28 14:39:24.460 UTC [1275] ERROR:  relation "goose_db_version" does not exist at character 36906niks3-go-unit-tests> 2026-06-28 14:39:24.460 UTC [1275] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC907niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure908niks3-go-unit-tests> 2026-06-28 14:39:24.460 UTC [1255] ERROR:  relation "goose_db_version" does not exist at character 36909niks3-go-unit-tests> 2026-06-28 14:39:24.460 UTC [1255] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC910niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure911niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart912niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart913niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts914niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts915niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure916niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts917niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/918niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart919niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received request for more parts method=POST path=/920niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete multipart upload request method=POST path=/921niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (60.42ms)922niks3-go-unit-tests> 2026-06-28 14:39:24.470 UTC [1272] ERROR:  relation "goose_db_version" does not exist at character 36923niks3-go-unit-tests> 2026-06-28 14:39:24.470 UTC [1272] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC924niks3-go-unit-tests> 2026-06-28 14:39:24.471 UTC [1274] ERROR:  relation "goose_db_version" does not exist at character 36925niks3-go-unit-tests> 2026-06-28 14:39:24.471 UTC [1274] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC926niks3-go-unit-tests> 2026-06-28 14:39:24.473 UTC [1302] ERROR:  relation "goose_db_version" does not exist at character 36927niks3-go-unit-tests> 2026-06-28 14:39:24.473 UTC [1302] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC928niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (7.1ms)929niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (24.78ms)930niks3-go-unit-tests> 2026/06/28 14:39:24 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=189.990976ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures931niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (55.98ms)932niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000933niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (4.55ms)934niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (92.86ms)935niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (3.63ms)936niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 2937niks3-go-unit-tests> 2026/06/28 14:39:24 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"938niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.29s)939niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (116.47ms)940niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (104.8ms)941niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (120.69ms)942niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (18.55ms)943niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (100.55ms)944niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (99.59ms)945niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (10.4ms)946niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (5.95ms)947niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (8.09ms)948niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (6.03ms)949niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (7.72ms)950niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (121.78ms)951niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (41.33ms)952niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (124.21ms)953niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (6.45ms)954niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (48.86ms)955niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (50.8ms)956niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (16.07ms)957niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (54.93ms)958niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (52.49ms)959niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (23.09ms)960niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000961niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (60.97ms)962niks3-go-unit-tests> 2026-06-28 14:39:24.650 UTC [1312] ERROR:  relation "goose_db_version" does not exist at character 36963niks3-go-unit-tests> 2026-06-28 14:39:24.650 UTC [1312] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC964niks3-go-unit-tests> 2026-06-28 14:39:24.652 UTC [1313] ERROR:  relation "goose_db_version" does not exist at character 36965niks3-go-unit-tests> 2026-06-28 14:39:24.652 UTC [1313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC966niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (6.85ms)967niks3-go-unit-tests> 2026-06-28 14:39:24.656 UTC [1314] ERROR:  relation "goose_db_version" does not exist at character 36968niks3-go-unit-tests> 2026-06-28 14:39:24.656 UTC [1314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC969niks3-go-unit-tests> 2026-06-28 14:39:24.657 UTC [1315] ERROR:  relation "goose_db_version" does not exist at character 36970niks3-go-unit-tests> 2026-06-28 14:39:24.657 UTC [1315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC971niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (6.95ms)972niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 2973niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (17.71ms)974niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (19.32ms)975niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000976niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (33.09ms)977niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (13.63ms)978niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (18.08ms)979niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000980niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (21.45ms)981niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (21.55ms)982niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000983niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000984niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 20260628120000985niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (5.56ms)986niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (4.96ms)987niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (5.08ms)988niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (6.1ms)989niks3-go-unit-tests> 2026-06-28 14:39:24.672 UTC [1316] ERROR:  relation "goose_db_version" does not exist at character 36990niks3-go-unit-tests> 2026-06-28 14:39:24.672 UTC [1316] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC991niks3-go-unit-tests> 2026-06-28 14:39:24.679 UTC [1317] ERROR:  relation "goose_db_version" does not exist at character 36992niks3-go-unit-tests> 2026-06-28 14:39:24.679 UTC [1317] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC993niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (15.05ms)994niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (11.16ms)995niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 2996niks3-go-unit-tests> 2026-06-28 14:39:24.681 UTC [1318] ERROR:  relation "goose_db_version" does not exist at character 36997niks3-go-unit-tests> 2026-06-28 14:39:24.681 UTC [1318] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC998niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (13.67ms)999niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (13.75ms)1000niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21001niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21002niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (20.73ms)1003niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001004niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (12.34ms)1005niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21006niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (20.57ms)1007niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001008niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1009niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received cleanup request method=DELETE path=/api/pending_closures1010niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (7.98ms)1011niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21012niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (6.1ms)1013niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (6.05ms)1014niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Aborted multipart uploads count=01015niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1016niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:24.693864613Z","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(386)"}1017niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket81018niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (4.82ms)1019niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21020niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (7.02ms)1021niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21022niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket91023niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1024niks3-go-unit-tests> 2026/06/28 14:39:24 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=428.255402ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1025niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.43s)1026niks3-go-unit-tests> 2026-06-28 14:39:24.714 UTC [1330] ERROR:  relation "goose_db_version" does not exist at character 361027niks3-go-unit-tests> 2026-06-28 14:39:24.714 UTC [1330] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1028niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received cleanup request method=DELETE path=/api/pending_closures1029niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Aborted multipart uploads count=11030niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (54.32ms)1031niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1032niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1033niks3-go-unit-tests> 2026-06-28 14:39:24.725 UTC [1255] ERROR:  Closure does not exist: id=11034niks3-go-unit-tests> 2026-06-28 14:39:24.725 UTC [1255] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1035niks3-go-unit-tests> 2026-06-28 14:39:24.725 UTC [1255] STATEMENT:  -- name: CommitPendingClosure :exec1036niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1037niks3-go-unit-tests> 	1038niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.44s)1039niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (57.45ms)1040niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:24.726807554Z","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(626)"}1041niks3-go-unit-tests> {"timestamp":"2026-06-28T14:39:24.726866724Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket4, 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(626)"}1042niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (5.32ms)1043niks3-go-unit-tests> 2026/06/28 14:39:24 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=ZmNkZmE1NGEtN2M0Mi00NGZhLTlmZWEtMDQyYTVhNzhlODExLmZmNDJhYWY1LWU4NDEtNGE2ZS1iZWE3LWI0NDQ1NDA4ZGRhN3gxNzgyNjU3NTY0NzAxMjMyNDIw1044niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.45s)1045niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (4.27ms)1046niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZmNkZmE1NGEtN2M0Mi00NGZhLTlmZWEtMDQyYTVhNzhlODExLmZmNDJhYWY1LWU4NDEtNGE2ZS1iZWE3LWI0NDQ1NDA4ZGRhN3gxNzgyNjU3NTY0NzAxMjMyNDIw parts=11047niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.45s)1048niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.45s)1049niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (64.57ms)1050niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (9.62ms)1051niks3-go-unit-tests> === NAME  TestClientMultipleUploads1052niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads2979336459/001/store/6mciv9a16sli7dzh6q8w64i3fhrbvvzf-test-file-0.txt1053niks3-go-unit-tests> 2026-06-28 14:39:24.744 UTC [1365] ERROR:  relation "goose_db_version" does not exist at character 361054niks3-go-unit-tests> 2026-06-28 14:39:24.744 UTC [1365] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1055niks3-go-unit-tests> 2026-06-28 14:39:24.744 UTC [1366] ERROR:  relation "goose_db_version" does not exist at character 361056niks3-go-unit-tests> 2026-06-28 14:39:24.744 UTC [1366] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1057niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (62.8ms)1058niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (10.24ms)1059niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (17.71ms)1060niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (36.39ms)1061niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (10.92ms)1062niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001063niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (7.14ms)1064niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.81ms)1065niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (5.26ms)1066niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (47.35ms)1067niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (3.58ms)1068niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21069niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (27.08ms)1070niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (6.61ms)1071niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (10.5ms)1072niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001073niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (13.8ms)1074niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (5.34ms)1075niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (7.95ms)1076niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1077niks3-go-unit-tests> 2026-06-28 14:39:24.761 UTC [1368] ERROR:  relation "goose_db_version" does not exist at character 361078niks3-go-unit-tests> 2026-06-28 14:39:24.761 UTC [1368] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1079niks3-go-unit-tests> 2026-06-28 14:39:24.761 UTC [1371] ERROR:  relation "goose_db_version" does not exist at character 361080niks3-go-unit-tests> 2026-06-28 14:39:24.761 UTC [1371] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1081niks3-go-unit-tests> 2026-06-28 14:39:24.762 UTC [1370] ERROR:  relation "goose_db_version" does not exist at character 361082niks3-go-unit-tests> 2026-06-28 14:39:24.762 UTC [1370] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1083niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (4.49ms)1084niks3-go-unit-tests> 2026-06-28 14:39:24.763 UTC [1372] ERROR:  relation "goose_db_version" does not exist at character 361085niks3-go-unit-tests> 2026-06-28 14:39:24.763 UTC [1372] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1086niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (5.11ms)1087niks3-go-unit-tests> 2026-06-28 14:39:24.765 UTC [1388] ERROR:  relation "goose_db_version" does not exist at character 361088niks3-go-unit-tests> 2026-06-28 14:39:24.765 UTC [1388] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1089niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.75ms)1090niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001091niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.72ms)1092niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001093niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (63.08ms)1094niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.81ms)1095niks3-go-unit-tests> 2026-06-28 14:39:24.767 UTC [1390] ERROR:  relation "goose_db_version" does not exist at character 361096niks3-go-unit-tests> 2026-06-28 14:39:24.767 UTC [1390] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1097niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (7.62ms)1098niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.81ms)1099niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21100niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (17.21ms)1101niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.64ms)1102niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001103niks3-go-unit-tests> 2026-06-28 14:39:24.767 UTC [1389] ERROR:  relation "goose_db_version" does not exist at character 361104niks3-go-unit-tests> 2026-06-28 14:39:24.767 UTC [1389] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1105niks3-go-unit-tests> 2026-06-28 14:39:24.769 UTC [1392] ERROR:  relation "goose_db_version" does not exist at character 361106niks3-go-unit-tests> 2026-06-28 14:39:24.769 UTC [1392] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1107niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.52ms)1108niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.49ms)1109niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.61ms)1110niks3-go-unit-tests> 2026-06-28 14:39:24.770 UTC [1399] ERROR:  relation "goose_db_version" does not exist at character 361111niks3-go-unit-tests> 2026-06-28 14:39:24.770 UTC [1399] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1112niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1113niks3-go-unit-tests> 2026-06-28 14:39:24.770 UTC [1410] ERROR:  relation "goose_db_version" does not exist at character 361114niks3-go-unit-tests> 2026-06-28 14:39:24.770 UTC [1410] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1115niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.49ms)1116niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.96ms)1117niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads2979336459/001/store/dr9pkkkvvh9xa13hx1lla0vxw62ddvy8-test-file-1.txt1118niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.7ms)1119niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21120niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.71ms)1121niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21122niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.15ms)1123niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001124niks3-go-unit-tests> === NAME  TestClientWithDependencies1125niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2595314427/001/store/037msvgm650y1jcpbxh4f52r1gm0yd3b-test-script1126niks3-go-unit-tests> 2026/06/28 14:39:24 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1127niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.47ms)1128niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.49s)1129niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21130niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.22ms)1131niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001132niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.81ms)1133niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.24ms)1134niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (13.85ms)1135niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.78ms)1136niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.86ms)1137niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket141138niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.49s)1139niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket131140niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.58ms)1141niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21142niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.64ms)1143niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21144niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.45ms)1145niks3-go-unit-tests> 2026-06-28 14:39:24.778 UTC [1411] ERROR:  relation "goose_db_version" does not exist at character 361146niks3-go-unit-tests> 2026-06-28 14:39:24.778 UTC [1411] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1147niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.49s)1148niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.43ms)1149niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001150niks3-go-unit-tests> 2026-06-28 14:39:24.780 UTC [1414] ERROR:  relation "goose_db_version" does not exist at character 361151niks3-go-unit-tests> 2026-06-28 14:39:24.780 UTC [1414] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1152niks3-go-unit-tests> 2026-06-28 14:39:24.780 UTC [1415] ERROR:  relation "goose_db_version" does not exist at character 361153niks3-go-unit-tests> 2026-06-28 14:39:24.780 UTC [1415] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1154niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.79ms)1155niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001156niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.19ms)1157niks3-go-unit-tests> 2026-06-28 14:39:24.783 UTC [1416] ERROR:  relation "goose_db_version" does not exist at character 361158niks3-go-unit-tests> 2026-06-28 14:39:24.783 UTC [1416] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1159niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.35ms)1160niks3-go-unit-tests> 2026-06-28 14:39:24.784 UTC [1417] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-28 14:39:24.784 UTC [1417] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (9.67ms)1163niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.31ms)1164niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (9.47ms)1165niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (9.55ms)1166niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.82ms)1167niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21168niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (3.63ms)1169niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001170niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.98ms)1171niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21172niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (11.71ms)1173niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (11.45ms)1174niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.67ms)1175niks3-go-unit-tests> 2026-06-28 14:39:24.787 UTC [1420] ERROR:  relation "goose_db_version" does not exist at character 361176niks3-go-unit-tests> 2026-06-28 14:39:24.787 UTC [1420] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1177niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (10.63ms)1178niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.77ms)1179niks3-go-unit-tests> 2026-06-28 14:39:24.788 UTC [1421] ERROR:  relation "goose_db_version" does not exist at character 361180niks3-go-unit-tests> 2026-06-28 14:39:24.788 UTC [1421] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1181niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.96ms)1182niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (11.97ms)1183niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.18ms)1184niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.45ms)1185niks3-go-unit-tests> 2026-06-28 14:39:24.790 UTC [1422] ERROR:  relation "goose_db_version" does not exist at character 361186niks3-go-unit-tests> 2026-06-28 14:39:24.790 UTC [1422] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1187niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.37ms)1188niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.72ms)1189niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.46ms)1190niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (14.1ms)1191niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (5.4ms)1192niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (6.3ms)1193niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (4.81ms)1194niks3-go-unit-tests> 2026-06-28 14:39:24.793 UTC [1424] ERROR:  relation "goose_db_version" does not exist at character 361195niks3-go-unit-tests> 2026-06-28 14:39:24.793 UTC [1424] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1196niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (4.49ms)1197niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21198niks3-go-unit-tests> 2026-06-28 14:39:24.794 UTC [1425] ERROR:  relation "goose_db_version" does not exist at character 361199niks3-go-unit-tests> 2026-06-28 14:39:24.794 UTC [1425] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1200niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1427] ERROR:  relation "goose_db_version" does not exist at character 361201niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1427] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1202niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1423] ERROR:  relation "goose_db_version" does not exist at character 361203niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1423] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1204niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1426] ERROR:  relation "goose_db_version" does not exist at character 361205niks3-go-unit-tests> 2026-06-28 14:39:24.795 UTC [1426] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1206niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (5.97ms)1207niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (15.47ms)1208niks3-go-unit-tests> === NAME  TestClientWithDependencies1209niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1210niks3-go-unit-tests> === NAME  TestClientMultipleUploads1211niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads2979336459/001/store/0yn76k9ycgnp6xkrx09q96zxwqjl5jnm-test-file-2.txt1212niks3-go-unit-tests> 2026-06-28 14:39:24.801 UTC [1445] ERROR:  relation "goose_db_version" does not exist at character 361213niks3-go-unit-tests> 2026-06-28 14:39:24.801 UTC [1445] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1214niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (11.05ms)1215niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001216niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (8.48ms)1217niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (8.54ms)1218niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001219niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001220niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (8.5ms)1221niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (15.27ms)1222niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (13ms)1223niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (16.25ms)1224niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (8.65ms)1225niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (9.92ms)1226niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (8.91ms)1227niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (9.61ms)1228niks3-go-unit-tests> === NAME  TestClientIntegration1229niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration1603362195/002/store/57313sh9mrw3s13afmxcbpqdrmhk1378-test-file.txt1230niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1231niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug3046083822/001/store/ciiwf6ki467bdr3vxdgisgrzixpnckix-file1.txt1232niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.62s)1233niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received cleanup request method=DELETE path=/api/pending_closures1234niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (104.56ms)1235niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (105.38ms)1236niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (121.24ms)1237niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (126.97ms)1238niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (105.91ms)1239niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (35.48ms)1240niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.7ms)1241niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.12ms)1242niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Aborted multipart uploads count=11243niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (108.75ms)1244niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (108.55ms)1245niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.61ms)1246niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.87ms)1247niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (108.68ms)1248niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001249niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.16ms)1250niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (4.16ms)1251niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (108.7ms)1252niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.83ms)1253niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.95ms)1254niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.86ms)1255niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001256niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.61ms)1257niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.24ms)1258niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.69ms)1259niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001260niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.61ms)1261niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.56ms)1262niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.61ms)1263niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.81ms)1264niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.56ms)1265niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21266niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21267niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21268niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (7.01ms)1269niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001270niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.39ms)1271niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Aborted multipart uploads count=01272niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.34ms)1273niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.3ms)1274niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (3.58ms)1275niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.67ms)1276niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.46ms)1277niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.5ms)1278niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (5.41ms)1279niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (5.55ms)1280niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.63s)1281niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.63s)1282niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (3.05ms)1283niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21284niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (4.73ms)1285niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (115.66ms)1286niks3-go-unit-tests> 2026/06/28 14:39:24 WARN Force mode enabled - objects will be deleted immediately without grace period1287niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (3.26ms)1288niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (4.75ms)1289niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (11.95ms)1290niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (6.72ms)1291niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (7.04ms)1292niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.09ms)1293niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (12.15ms)1294niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001295niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (7.96ms)1296niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001297niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21298niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (11.84ms)1299niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (6.7ms)1300niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (6.72ms)1301niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.29ms)1302niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001303niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.35ms)1304niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21305niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.54ms)1306niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (3.54ms)1307niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001308niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.36ms)1309niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001310niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.49ms)1311niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21312niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.51ms)1313niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001314niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.35ms)1315niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.59ms)1316niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (6.26ms)1317niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001318niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (3.15ms)1319niks3-go-unit-tests> 2026/06/28 14:39:24 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=01320niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.13ms)1321niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.69ms)1322niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.98ms)1323niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20241026095416_initial_model.sql (15.15ms)1324niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001325niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Vacuumed table table=pending_closures1326niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.41ms)1327niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Vacuumed table table=pending_objects1328niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.64s)1329niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1330niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: bound subjects configured but subject DN unavailable1331niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1332niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.64s)1333niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.53ms)1334niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21335niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.96ms)1336niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.1ms)1337niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.38ms)1338niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001339niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (4.01ms)1340niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2.01ms)1341niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21342niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Vacuumed table table=multipart_uploads1343niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.89ms)1344niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001345niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.95ms)1346niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001347niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.62ms)1348niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.03ms)1349niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001350niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Vacuumed table table=closures1351niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.64s)1352niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.67ms)1353niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21354niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.3ms)1355niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251210153512_drop_unused_gin_index.sql (2.53ms)1356niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (2.82ms)1357niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4ms)1358niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.73ms)1359niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.48ms)1360niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21361niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Vacuumed table table=objects1362niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket261363niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.72ms)1364niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21365niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.75ms)1366niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.08ms)1367niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21368niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (1.97ms)1369niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.36ms)1370niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21371niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (2ms)1372niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21373niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.17ms)1374niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.64s)1375niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.77ms)1376niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.71ms)1377niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21378niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (3.19ms)1379niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.13ms)1380niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21381niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1382niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.65s)1383niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.1ms)1384niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21385niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1386niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1387niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1388niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (2.87ms)1389niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001390niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.65s)1391niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1392niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20251218171726_add_pins.sql (4.17ms)1393niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)1394niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001395niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.65s)1396niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.49ms)1397niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21398niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (4.69ms)1399niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001400niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (5.21ms)1401niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001402niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (1.43ms)1403niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1404niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.65s)1405niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (1.62ms)1406niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Created nix-cache-info in bucket bucket=bucket281407niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.1ms)1408niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21409niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (1.7ms)1410niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1411niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.65s)1412niks3-go-unit-tests> 2026/06/28 14:39:24 OK   20260628120000_add_object_size_and_stats.sql (2.82ms)1413niks3-go-unit-tests> 2026/06/28 14:39:24 goose: successfully migrated database to version: 202606281200001414niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.29ms)1415niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21416niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.63ms)1417niks3-go-unit-tests> --- PASS: TestGCMetrics (0.65s)1418niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.41ms)1419niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21420niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.18ms)1421niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21422niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1423niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1424niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1425niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1426niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1427niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1428niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1429niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1430niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1431niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1432niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1433niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1434niks3-go-unit-tests> 2026/06/28 14:39:24 OK   1_commit_pending_closure.sql (2.93ms)1435niks3-go-unit-tests> 2026/06/28 14:39:24 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]1436niks3-go-unit-tests> 2026/06/28 14:39:24 OK   2_object_stats_trigger.sql (1.63ms)1437niks3-go-unit-tests> 2026/06/28 14:39:24 goose: up to current file version: 21438niks3-go-unit-tests> 2026/06/28 14:39:24 INFO OIDC auth successful provider=test1439niks3-go-unit-tests> 2026/06/28 14:39:24 WARN Authentication failed token_preview=eyJhbGciOi...gU9uHTjv2g 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]1440niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1441niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.65s)1442niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1443niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1444niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1445niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1446niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1447niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1448niks3-go-unit-tests> 2026/06/28 14:39:24 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1449niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.66s)1450niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.66s)1451niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1452niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.66s)1453niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1454niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1455niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 037msvgm650y1jcpbxh4f52r1gm0yd3b-test-script (136B)1456niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received uploads request method=POST path=/api/pending_closures1457niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.66s)1458niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1459niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 0yn76k9ycgnp6xkrx09q96zxwqjl5jnm-test-file-2.txt (160B)1460niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading dr9pkkkvvh9xa13hx1lla0vxw62ddvy8-test-file-1.txt (160B)1461niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 6mciv9a16sli7dzh6q8w64i3fhrbvvzf-test-file-0.txt (160B)1462niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1463niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Signed narinfos id=1 count=11464niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 1 narinfos1465niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1466niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1467niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Signed narinfos id=1 count=11468niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1469niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Signed narinfos id=2 count=11470niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1471niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Signed narinfos id=3 count=11472niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Uploading 3 narinfos1473niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1474niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Completed upload id=11475niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Upload complete. (54ms)1476niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Completed upload id=31477niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1478niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Completed upload id=11479niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1480niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Completed upload id=21481niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Upload complete. (139ms)1482niks3-go-unit-tests> === NAME  TestClientMultipleUploads1483niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 164.713577ms1484niks3-go-unit-tests> === NAME  TestClientWithDependencies1485niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2595314427/001/store) requires matching store prefix1486niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.69s)1487niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.69s)1488niks3-go-unit-tests> 2026/06/28 14:39:24 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1489niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1490niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC4287148681/001/store/8hm2bya2gvw7yib1y9icq50y4vvc5cry-pinned-file.txt1491niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC4287148681/001/store/6n3ljqilaw44k433swlx30g9jc925kp5-unpinned-file.txt1492niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1493niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1494niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading ciiwf6ki467bdr3vxdgisgrzixpnckix-file1.txt (160B)1495niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.74s)1496niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1497niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1498niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=1 count=11499niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1500niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1501niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1502niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 57313sh9mrw3s13afmxcbpqdrmhk1378-test-file.txt (152B)1503niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11504niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (91ms)1505niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1506niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1507niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug3046083822/001/store/ciiwf6ki467bdr3vxdgisgrzixpnckix-file1.txt1508niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1509niks3-go-unit-tests>         Compression: zstd1510niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1511niks3-go-unit-tests>         NarSize: 1601512niks3-go-unit-tests>         References: 1513niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1514niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1515niks3-go-unit-tests> === NAME  TestClientCADerivations1516niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations3774660405/001/store/r89w4f4jdkl8ni8cv725fvlqrh9d24ss-ca-test1517niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=1 count=11518niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1519niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1520niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1521niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1522niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1523niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1524niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11525niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (99ms)1526niks3-go-unit-tests> === NAME  TestClientIntegration1527niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1528niks3-go-unit-tests>         StorePath: /build/TestClientIntegration1603362195/002/store/57313sh9mrw3s13afmxcbpqdrmhk1378-test-file.txt1529niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1530niks3-go-unit-tests>         Compression: zstd1531niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11532niks3-go-unit-tests>         NarSize: 1521533niks3-go-unit-tests>         References: 1534niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11535niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1536niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1537niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1538niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1539niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1540niks3-go-unit-tests> === NAME  TestClientCADerivations1541niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1542niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1543niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug3046083822/001/store/1phl4vnfs8h05ra7x13xqqks85lk9ai7-file2.txt1544niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Starting cleanup of old closures method=DELETE path=/api/closures1545niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Garbage collection started1546niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1547niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Aborted multipart uploads count=01548niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Force mode enabled - objects will be deleted immediately without grace period1549niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1550niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 8hm2bya2gvw7yib1y9icq50y4vvc5cry-pinned-file.txt (128B)1551niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1552niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=1 count=11553niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1554niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1555niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11556niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (79ms)1557niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.84s)1558niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=750.93105ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1559niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1560niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1561niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1562niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1563niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=2 count=11564niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1565niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1566niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading r89w4f4jdkl8ni8cv725fvlqrh9d24ss-ca-test (144B)1567niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1568niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=21569niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (65ms)1570niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1571niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1572niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug3046083822/001/store/1phl4vnfs8h05ra7x13xqqks85lk9ai7-file2.txt1573niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1574niks3-go-unit-tests>         Compression: zstd1575niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1576niks3-go-unit-tests>         NarSize: 1601577niks3-go-unit-tests>         References: 1578niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1579niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1580niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1581niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1582niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1583niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=1 count=11584niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.88s)1585niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1586niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1587niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11588niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (80ms)1589niks3-go-unit-tests> === NAME  TestClientCADerivations1590niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations3774660405/001/store/r89w4f4jdkl8ni8cv725fvlqrh9d24ss-ca-test1591niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1592niks3-go-unit-tests>         Compression: zstd1593niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1594niks3-go-unit-tests>         NarSize: 1441595niks3-go-unit-tests>         References: 1596niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations3774660405/001/store/da2rnnlmsjpcp7y76c972af32higy6z7-ca-test.drv1597niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1598niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1599niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1600niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1601niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1602niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1603niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1604niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1605niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1606niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1607niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1608niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.89s)1609niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1610niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1611niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZmNkZmE1NGEtN2M0Mi00NGZhLTlmZWEtMDQyYTVhNzhlODExLjRiZTExZDcyLTQ0NGQtNDViZS1hMGQyLWIwZjA3OTczZTYwOXgxNzgyNjU3NTY0OTM1OTYxNTEy parts=101612niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1613niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1614niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 6n3ljqilaw44k433swlx30g9jc925kp5-unpinned-file.txt (128B)1615niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11616niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1617niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1618niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Signed narinfos id=2 count=11619niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Uploading 1 narinfos1620niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1621niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1622niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Found objects in DB but missing from S3, will re-upload count=11623niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.91s)1624niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1625niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=21626niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Upload complete. (65ms)1627niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1628niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZmNkZmE1NGEtN2M0Mi00NGZhLTlmZWEtMDQyYTVhNzhlODExLmI2ZTM3MjgwLTlhMTAtNDE1YS04NGVhLWUwOWY5MzBlNzZkZXgxNzgyNjU3NTY0OTM1ODI1MjYy parts=101629niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1630niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed upload id=11631niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001632niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received uploads request method=POST path=/api/pending_closures1633niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Starting cleanup of old closures method=DELETE path=/api/closures1634niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Aborted multipart uploads count=01635niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received create pin request method=POST path=/api/pins/myapp1636niks3-go-unit-tests> 2026/06/28 14:39:25 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=01637niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_closures1638niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC4287148681/001/store/8hm2bya2gvw7yib1y9icq50y4vvc5cry-pinned-file.txt narinfo_key=8hm2bya2gvw7yib1y9icq50y4vvc5cry.narinfo1639niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_objects1640niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Starting cleanup of old closures method=DELETE path=/api/closures1641niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Garbage collection started1642niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=multipart_uploads1643niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=closures1644niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=objects1645niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Aborted multipart uploads count=01646niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Force mode enabled - objects will be deleted immediately without grace period1647niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1648niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZmNkZmE1NGEtN2M0Mi00NGZhLTlmZWEtMDQyYTVhNzhlODExLmNmNjMxYTNhLTYzZjAtNDU3OC05MzcyLTAzMzhlZDE2ZTVhNHgxNzgyNjU3NTY0OTM5NjgzNjc2 parts=121649niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.97s)1650niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001651niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.98s)1652niks3-go-unit-tests> === NAME  TestClientCADerivations1653niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1654niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1655niks3-go-unit-tests>         error: binary cache 's3://bucket28?endpoint=http://localhost:45617&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations3774660405/001/store'1656niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11657niks3-go-unit-tests> --- PASS: TestClientCADerivations (1.01s)1658niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.18s)1659niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1660niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1661niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.86s)1662niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1663niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1664niks3-go-unit-tests> 2026/06/28 14:39:25 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=01665niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_closures1666niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_objects1667niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=multipart_uploads1668niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=closures1669niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=objects1670niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1671niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=01672niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_closures1673niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=pending_objects1674niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=multipart_uploads1675niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=closures1676niks3-go-unit-tests> 2026/06/28 14:39:25 INFO Vacuumed table table=objects1677niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1678niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201679niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101680niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101681niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.41s)1682niks3-go-unit-tests> 2026/06/28 14:39:25 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.618859059s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1683niks3-go-unit-tests> 2026/06/28 14:39:27 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01684niks3-go-unit-tests> === NAME  TestClientIntegration1685niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1686niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1687niks3-go-unit-tests> --- PASS: TestClientIntegration (2.80s)1688niks3-go-unit-tests> 2026/06/28 14:39:27 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01689niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1690niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1691niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.95s)1692niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1693niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.68s)1694niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.77s)1695niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.23s)1696niks3-go-unit-tests> 2026/06/28 14:39:29 WARN Rate limiter enabled after throttle name=s3-test rate=51697niks3-go-unit-tests> 2026/06/28 14:39:29 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1698niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1699niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101700niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001701niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.69s)1702niks3-go-unit-tests> 2026-06-28 14:44:23.783 UTC [305] LOG:  checkpoint starting: time1703niks3-go-unit-tests> 2026-06-28 14:48:53.164 UTC [305] LOG:  checkpoint complete: wrote 6718 buffers (41.0%); 0 WAL file(s) added, 0 removed, 12 recycled; write=269.224 s, sync=0.149 s, total=269.382 s; sync files=14432, longest=0.001 s, average=0.001 s; distance=194358 kB, estimate=194358 kB; lsn=0/D2BA258, redo lsn=0/D2BA1C81704error: Nix daemon disconnected unexpectedly (maybe it crashed?)1705error: Cannot build '/nix/store/azl6f3iw15qwdms14jx8xijfwjz9ck3g-niks3-go-unit-tests.drv'.1706       Reason: builder failed with exit code 1.1707       Output paths:1708         /nix/store/s8nwvdqr33ijcpch3hw3yn2pdv0azzxq-niks3-go-unit-tests