nixbot

1this derivation will be built:2  /nix/store/zj7s8x4s1lg9rjyrhmkqqnzl9lz42z5v-niks3-go-unit-tests.drv3building '/nix/store/zj7s8x4s1lg9rjyrhmkqqnzl9lz42z5v-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/zj7s8x4s1lg9rjyrhmkqqnzl9lz42z5v-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestResolveStorePath76niks3-go-unit-tests> === CONT  TestDumpPathWriterError77niks3-go-unit-tests> === CONT  TestEncodeNixBase3278niks3-go-unit-tests> === CONT  TestGetStorePathHash79niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash80niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path81niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer82niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path83niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists84niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash85niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists86niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility87niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths88niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths89niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths90niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths91niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths92niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths93niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input94niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths95niks3-go-unit-tests> === CONT  TestParsePathInfoJSON96niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input97niks3-go-unit-tests> === CONT  TestDumpPathSingleFile98niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing99niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing100niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists101niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing102niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field103niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field104niks3-go-unit-tests> === CONT  TestRateLimiterFeedback105niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport106niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches107niks3-go-unit-tests> === CONT  TestStaticToken108niks3-go-unit-tests> === CONT  TestSetClientTLSErrors109niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken110niks3-go-unit-tests> === CONT  TestFileTokenMissing111niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand112niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh113niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall114niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails115niks3-go-unit-tests> === CONT  TestFileTokenEmpty116niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON117niks3-go-unit-tests> === CONT  TestPartSizeForNAR118niks3-go-unit-tests> === CONT  TestShellSplitErrors119niks3-go-unit-tests> === CONT  TestSetClientTLS120niks3-go-unit-tests> === CONT  TestCaseHackSuffix121niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody122niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix123niks3-go-unit-tests> === CONT  TestConvertHashToNix32124niks3-go-unit-tests> === CONT  TestShellSplit125niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess126niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error127niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)128niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format129niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility130niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash131niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format132niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)133niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)134niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input135niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text136niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum137niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash138niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text139niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter140niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32141niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter142niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)143niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum144niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32145niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)146niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format147niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)148niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive149niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon150niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format151niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)152niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum153niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)154niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter155niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)156niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)157niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format158niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum159niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)160niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)161niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)162niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)163niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)164niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)165niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter166niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input167niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum168niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter169niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter170niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter171niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input172niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter173niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only174niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter175niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter176niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter177niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum178niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)179niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter180niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts181niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only182niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts183niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB184niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB185niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON186niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object187niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object188niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB189niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB190niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB191niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts192niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum193niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum194niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum195niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB196niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object197niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON198niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format199niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only200niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input201niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error202niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format203niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive204niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format205niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text206niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text207niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method208niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method209niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field210niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)211niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method212niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter enabled after throttle name=server-test rate=5213niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon214niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format215niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format216niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format217niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI218niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text219niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI220niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)221niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text222niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error223niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive224niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error225niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error226niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error227niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path228niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON229niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32230niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error231niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter enabled after throttle name=server-test rate=5232niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error233niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error234niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter enabled after throttle name=server-test rate=5235niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format236niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file237niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44913238niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:34477239niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file240niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512241niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)242niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)243niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)244niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)245niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)246niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)247niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file248niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file249niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter backed off name=server-test rate=5250niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)251niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)252niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)253niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)254niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file255niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file256niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44913257niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512258niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)259niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)260niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)261niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)262niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)263niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)264niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)265niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)266niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI267niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)268niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.01s)269niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)270niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)271niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)272niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)273niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon274niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.01s)275niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)276niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)277niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)278niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)279niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)280niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)281niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)282niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter backed off name=server-test rate=5283niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter enabled after throttle name=server-test rate=5284niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file285niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:37305286niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file287niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512288niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file289niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file290niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file291niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)292niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)293niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)294niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file295niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.01s)296niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)297niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)298niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)299niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)300niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)301niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Rate limiter backed off name=server-test rate=5302niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)303niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)304niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)305niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)306niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)307niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert308niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert309niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA310niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA311niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport312niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport313niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert314niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport315niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA316niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)317niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)318niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)319niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)320niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)321niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)322niks3-go-unit-tests> 2026/06/29 09:29:17 http: TLS handshake error from 127.0.0.1:45618: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)324niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.02s)325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)329niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.08s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.11s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.01s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres2978210649/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/postgres2978210649/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres2978210649:5432 - no response364niks3-go-unit-tests> 2026-06-29 09:29:18.886 UTC [309] 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-29 09:29:18.886 UTC [309] LOG:  listening on Unix socket "/build/postgres2978210649/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-29 09:29:18.891 UTC [313] LOG:  database system was shut down at 2026-06-29 09:29:18 UTC367niks3-go-unit-tests> 2026-06-29 09:29:18.895 UTC [309] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres2978210649:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:19.285910513Z","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(769)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (1091) 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-29 09:29:19.354 UTC [1107] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-29 09:29:19.354 UTC [1107] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (7.82ms)404niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (1.62ms)405niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251218171726_add_pins.sql (3.16ms)406niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20260628120000_add_object_size_and_stats.sql (2.78ms)407niks3-go-unit-tests> 2026/06/29 09:29:19 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/29 09:29:19 OK   1_commit_pending_closure.sql (1.9ms)409niks3-go-unit-tests> 2026/06/29 09:29:19 OK   2_object_stats_trigger.sql (804.37µs)410niks3-go-unit-tests> 2026/06/29 09:29:19 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/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/29 09:29:19 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/29 09:29:19 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  TestMultipartCleanup524niks3-go-unit-tests> === CONT  TestReadProxyDisabled525niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys526niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info527niks3-go-unit-tests> === CONT  TestService_Rustfstest528niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed529niks3-go-unit-tests> === CONT  TestReadProxyHead530niks3-go-unit-tests> === CONT  TestServerTLSConfig531niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload532niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML533niks3-go-unit-tests> === CONT  TestObjectStatsTrigger534niks3-go-unit-tests> === CONT  TestReadProxy404535niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest536niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC537niks3-go-unit-tests> === CONT  TestClientIntegration538niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA539niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects540niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader541niks3-go-unit-tests> === CONT  TestClientErrorHandling542niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info543niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT544niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath545niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted546niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered547niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity548niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler549niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler550niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody551niks3-go-unit-tests> === CONT  TestProxyWriteTimeout552niks3-go-unit-tests> === CONT  TestIsValidUploadKey553niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists554niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle555niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo556niks3-go-unit-tests> === CONT  TestReadProxyNarinfo557niks3-go-unit-tests> === CONT  TestIsValidCachePath558niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo559niks3-go-unit-tests> === CONT  TestParseSingleRange560niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo561niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars562niks3-go-unit-tests> === CONT  TestService_NativeMTLS563niks3-go-unit-tests> === CONT  TestMetricsInventory564niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug565niks3-go-unit-tests> === CONT  TestGenerateLandingPage566niks3-go-unit-tests> === CONT  TestService_healthCheckHandler567niks3-go-unit-tests> === RUN   TestParseSingleRange/none568niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars569niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail570niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)571niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates572niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)573niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst574niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest575niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst576niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty577niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz578niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams579niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz580niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams581niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming582niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest583niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC584niks3-go-unit-tests> === CONT  TestClientCADerivations585niks3-go-unit-tests> === CONT  TestCacheStatsHandler586niks3-go-unit-tests> === CONT  TestCacheConfigHandler587niks3-go-unit-tests> === CONT  TestPinProtectsFromGC588niks3-go-unit-tests> === CONT  TestGCMetrics589niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences590niks3-go-unit-tests> === CONT  TestClientMultipleUploads591niks3-go-unit-tests> === CONT  TestClientWithDependencies592niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet593niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware594niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA595niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath596niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal597niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo598niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo599niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst600niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst601niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath602niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal603niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo604niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew605niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight606niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer607niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz608niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file609niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz610niks3-go-unit-tests> === PAUSE TestParseSingleRange/none611niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)612niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)613niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)614niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2615niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken616niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken617niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file618niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask619niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file620niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key621niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer622niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key623niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file624niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured625niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar626niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured627niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)628niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys629niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)630niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)631niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Starting HTTP server address=127.0.0.1:39507632niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit633niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2634niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable635niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit636niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable637niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored638niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored639niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken640niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash641niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash642niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty643niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed644niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed645niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain646niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath647niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls648niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls650niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA651niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing652niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing653niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log654niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log655niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file656niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file657niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar658niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys659niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file660niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable661niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator662niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file663niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar664niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key665niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty666niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar667niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key668niks3-go-unit-tests> === RUN   TestIsValidCachePath/log669niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size670niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal671niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log672niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator673niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer674niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name675niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys676niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start677niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info678niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key679niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key680niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation681niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured682niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator683niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received uploads request method=POST path=/684niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received uploads request method=POST path=/685niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received complete multipart upload request method=POST path=/686niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received request for more parts method=POST path=/687niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)688niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)689niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)690niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)691niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)692niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)693niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)694niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)695niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)696niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)697niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)698niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)699niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)700niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)701niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)702niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name703niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark704niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark705niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals706niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals707niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation708niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation709niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output710niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start711niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation712niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output713niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info714niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size715niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info716niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar717niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info718niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar719niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info720niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo721niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html722niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size723niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Shutdown signal received, draining in-flight requests timeout=10s724niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html725niks3-go-unit-tests> === RUN   TestParseSingleRange/closed726niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type727niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed728niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type729niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended730niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type731niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended732niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html733niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.08s)734niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)735niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)736niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)737niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)738niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html739niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type740niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent741niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size742niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent743niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size744niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle745niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle746niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix747niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e748niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix749niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type750niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type751niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal752niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e753niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal754niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size755niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar756niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u757niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u758niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size759niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar760niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path761niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute762niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path763niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty764niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte765niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty766niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte767niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute768niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash769niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key770niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash771niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key772niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension773niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type774niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF775niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension776niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type777niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF778niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute779niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF780niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type781niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash782niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type783niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst784niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info785niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo786niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation787niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar788niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal789niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type790niks3-go-unit-tests> 2026/06/29 09:29:19 INFO OIDC provider initialized name=test791niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals792niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF793niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark794niks3-go-unit-tests> === CONT  TestParseSingleRange/none795niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name796niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF797niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html798niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start799niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix800niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended801niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored802niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit803niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash804niks3-go-unit-tests> === CONT  TestParseSingleRange/closed805niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log806niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing807niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain808niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash809niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz810niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension811niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info812niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls813niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type814niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output815niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz816niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key817niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars818niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty819niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2820niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle821niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed822niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF823niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file824niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size825niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.08s)826niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)827niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)828niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)829niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)830niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)831niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)832niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)833niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)839niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)840niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)841niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)844niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)845niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)846niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)847niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)848niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty849niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size850niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte851niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo852niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.08s)853niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)854niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)855niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)856niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)857niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)858niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)859niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)860niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)861niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)862niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)863niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)864niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)865niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)866niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)867niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash868niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation869niks3-go-unit-tests> === CONT  TestIsValidCachePath/log870niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst871niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u872niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash873niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path874niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e875niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent876niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html877niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.08s)878niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)896niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)897niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)898niks3-go-unit-tests> 2026/06/29 09:29:19 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_closures899niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.15s)900niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure901niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure902niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart903niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart904niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts905niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts906niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure907niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts908niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received uploads request method=POST path=/909niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart910niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received request for more parts method=POST path=/911niks3-go-unit-tests> 2026/06/29 09:29:19 INFO Received complete multipart upload request method=POST path=/912niks3-go-unit-tests> 2026-06-29 09:29:19.812 UTC [1298] ERROR:  relation "goose_db_version" does not exist at character 36913niks3-go-unit-tests> 2026-06-29 09:29:19.812 UTC [1298] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC914niks3-go-unit-tests> 2026-06-29 09:29:19.813 UTC [1293] ERROR:  relation "goose_db_version" does not exist at character 36915niks3-go-unit-tests> 2026-06-29 09:29:19.813 UTC [1293] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC916niks3-go-unit-tests> 2026-06-29 09:29:19.813 UTC [1294] ERROR:  relation "goose_db_version" does not exist at character 36917niks3-go-unit-tests> 2026-06-29 09:29:19.813 UTC [1294] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC918niks3-go-unit-tests> 2026-06-29 09:29:19.814 UTC [1292] ERROR:  relation "goose_db_version" does not exist at character 36919niks3-go-unit-tests> 2026-06-29 09:29:19.814 UTC [1292] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC920niks3-go-unit-tests> 2026-06-29 09:29:19.814 UTC [1275] ERROR:  relation "goose_db_version" does not exist at character 36921niks3-go-unit-tests> 2026-06-29 09:29:19.814 UTC [1275] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC922niks3-go-unit-tests> 2026-06-29 09:29:19.831 UTC [1295] ERROR:  relation "goose_db_version" does not exist at character 36923niks3-go-unit-tests> 2026-06-29 09:29:19.831 UTC [1295] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC924niks3-go-unit-tests> 2026-06-29 09:29:19.831 UTC [1296] ERROR:  relation "goose_db_version" does not exist at character 36925niks3-go-unit-tests> 2026-06-29 09:29:19.831 UTC [1296] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC926niks3-go-unit-tests> 2026-06-29 09:29:19.833 UTC [1297] ERROR:  relation "goose_db_version" does not exist at character 36927niks3-go-unit-tests> 2026-06-29 09:29:19.833 UTC [1297] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC928niks3-go-unit-tests> 2026-06-29 09:29:19.833 UTC [1273] ERROR:  relation "goose_db_version" does not exist at character 36929niks3-go-unit-tests> 2026-06-29 09:29:19.833 UTC [1273] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC930niks3-go-unit-tests> 2026/06/29 09:29:19 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=182.773015ms 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-29 09:29:19.880 UTC [1313] ERROR:  relation "goose_db_version" does not exist at character 36932niks3-go-unit-tests> 2026-06-29 09:29:19.880 UTC [1313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC933niks3-go-unit-tests> 2026-06-29 09:29:19.881 UTC [1314] ERROR:  relation "goose_db_version" does not exist at character 36934niks3-go-unit-tests> 2026-06-29 09:29:19.881 UTC [1314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC935niks3-go-unit-tests> 2026-06-29 09:29:19.886 UTC [1315] ERROR:  relation "goose_db_version" does not exist at character 36936niks3-go-unit-tests> 2026-06-29 09:29:19.886 UTC [1315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC937niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (82.94ms)938niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (7.43ms)939niks3-go-unit-tests> 2026-06-29 09:29:19.945 UTC [1322] ERROR:  relation "goose_db_version" does not exist at character 36940niks3-go-unit-tests> 2026-06-29 09:29:19.945 UTC [1322] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC941niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (102.89ms)942niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251218171726_add_pins.sql (18.95ms)943niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (102.49ms)944niks3-go-unit-tests> 2026-06-29 09:29:19.951 UTC [1323] ERROR:  relation "goose_db_version" does not exist at character 36945niks3-go-unit-tests> 2026-06-29 09:29:19.951 UTC [1323] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC946niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (4.79ms)947niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (104.69ms)948niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (5.15ms)949niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (88.37ms)950niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (5.49ms)951niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (82.79ms)952niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (62.03ms)953niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (90.47ms)954niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (107.22ms)955niks3-go-unit-tests> 2026-06-29 09:29:19.966 UTC [1324] ERROR:  relation "goose_db_version" does not exist at character 36956niks3-go-unit-tests> 2026-06-29 09:29:19.966 UTC [1324] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC957niks3-go-unit-tests> 2026-06-29 09:29:19.966 UTC [1325] ERROR:  relation "goose_db_version" does not exist at character 36958niks3-go-unit-tests> 2026-06-29 09:29:19.966 UTC [1325] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC959niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (122.5ms)960niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20260628120000_add_object_size_and_stats.sql (22.07ms)961niks3-go-unit-tests> 2026/06/29 09:29:19 goose: successfully migrated database to version: 20260628120000962niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251210153512_drop_unused_gin_index.sql (15.37ms)963niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251218171726_add_pins.sql (19.78ms)964niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20241026095416_initial_model.sql (42.1ms)965niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251218171726_add_pins.sql (22.55ms)966niks3-go-unit-tests> 2026/06/29 09:29:19 OK   20251218171726_add_pins.sql (15.34ms)967niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (95.07ms)968niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1327] ERROR:  relation "goose_db_version" does not exist at character 36969niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1327] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC970niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1326] ERROR:  relation "goose_db_version" does not exist at character 36971niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1326] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC972niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 36973niks3-go-unit-tests> 2026-06-29 09:29:20.011 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC974niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (48.38ms)975niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (49.46ms)976niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (49.43ms)977niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (38.87ms)978niks3-go-unit-tests> 2026-06-29 09:29:20.012 UTC [1329] ERROR:  relation "goose_db_version" does not exist at character 36979niks3-go-unit-tests> 2026-06-29 09:29:20.012 UTC [1329] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC980niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (37.19ms)981niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 20260628120000982niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (12.59ms)983niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (13.3ms)984niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.7ms)985niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (14.36ms)986niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (14.59ms)987niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (14.76ms)988niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (16.1ms)989niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (11.86ms)990niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 2991niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (17.78ms)992niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"993niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: bound subjects configured but subject DN unavailable994niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"995niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.40s)996niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (24.1ms)997niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 20260628120000998niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (6.76ms)999niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21000niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (35.15ms)1001niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001002niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (28.31ms)1003niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (5.71ms)1004niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (38.97ms)1005niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001006niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (7.97ms)1007niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (34.58ms)1008niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (34.44ms)1009niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (34.86ms)1010niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (10.06ms)1011niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (11.83ms)1012niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21013niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (38.18ms)1014niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (36.57ms)1015niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1016niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.42s)1017niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (16.27ms)1018niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21019niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=415.449538ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1020niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (14.09ms)1021niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21022niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (49.02ms)1023niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (47.58ms)1024niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1025niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (23.09ms)1026niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001027niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (86.49ms)1028niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (32.44ms)1029niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001030niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (6.6ms)1031niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (22.5ms)1032niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001033niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (5.2ms)1034niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (5.12ms)1035niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (11.6ms)1036niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001037niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (25.87ms)1038niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001039niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (24.18ms)1040niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001041niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.44s)1042niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (30.32ms)1043niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001044niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (5.22ms)1045niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (16.8ms)1046niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001047niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (10.52ms)1048niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21049niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (9.04ms)1050niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (8.92ms)1051niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (5.23ms)1052niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21053niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21054niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (8.87ms)1055niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (8.69ms)1056niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (12.95ms)1057niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (7.36ms)1058niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (14.55ms)1059niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:20.092105547Z","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(769)"}1060niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.46s)1061niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (5.43ms)1062niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21063niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (5.63ms)1064niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21065niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1066niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:20.094223625Z","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(766)"}1067niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1068niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.46s)1069niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket111070niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.47s)1071niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.47s)1072niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (14.05ms)1073niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21074niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1075niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (17.12ms)1076niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (18.88ms)1077niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21078niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21079niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (104.83ms)1080niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.48s)1081niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (110.6ms)1082niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (5.58ms)1083niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket141084niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (26.32ms)1085niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001086niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (7.03ms)1087niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (8.78ms)1088niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (75.53ms)1089niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (80.26ms)1090niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (73.61ms)1091niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (77.93ms)1092niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (6.79ms)1093niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.49s)1094niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (3.28ms)1095niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21096niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (4.66ms)1097niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (4.87ms)1098niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (5.01ms)1099niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (4.95ms)1100niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (7.98ms)1101niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (8.41ms)1102niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001103niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (6.54ms)1104niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (6.46ms)1105niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (6.39ms)1106niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (7.72ms)1107niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (4.5ms)1108niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (8.18ms)1109niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001110niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.2ms)1111niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21112niks3-go-unit-tests> 2026-06-29 09:29:20.139 UTC [1348] ERROR:  relation "goose_db_version" does not exist at character 361113niks3-go-unit-tests> 2026-06-29 09:29:20.139 UTC [1348] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1114niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (6.38ms)1115niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001116niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (5.27ms)1117niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001118niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (6.59ms)1119niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001120niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (4.02ms)1121niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (6.68ms)1122niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001123niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1124niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug867243446/001/store/slx5qrnqglhrap80alivsj28sb4pax8a-file1.txt1125niks3-go-unit-tests> 2026-06-29 09:29:20.142 UTC [1349] ERROR:  relation "goose_db_version" does not exist at character 361126niks3-go-unit-tests> 2026-06-29 09:29:20.142 UTC [1349] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1127niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.45ms)1128niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21129niks3-go-unit-tests> 2026-06-29 09:29:20.144 UTC [1350] ERROR:  relation "goose_db_version" does not exist at character 361130niks3-go-unit-tests> 2026-06-29 09:29:20.144 UTC [1350] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1131niks3-go-unit-tests> 2026-06-29 09:29:20.144 UTC [1351] ERROR:  relation "goose_db_version" does not exist at character 361132niks3-go-unit-tests> 2026-06-29 09:29:20.144 UTC [1351] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1133niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.14ms)1134niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.31ms)1135niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.29ms)1136niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.51s)1137niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.52ms)1138niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (985.98µs)1139niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21140niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.78ms)1141niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21142niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.48ms)1143niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21144niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.49ms)1145niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21146niks3-go-unit-tests> 2026-06-29 09:29:20.147 UTC [1352] ERROR:  relation "goose_db_version" does not exist at character 361147niks3-go-unit-tests> 2026-06-29 09:29:20.147 UTC [1352] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1148niks3-go-unit-tests> 2026-06-29 09:29:20.148 UTC [1353] ERROR:  relation "goose_db_version" does not exist at character 361149niks3-go-unit-tests> 2026-06-29 09:29:20.148 UTC [1353] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1150niks3-go-unit-tests> 2026-06-29 09:29:20.149 UTC [1357] ERROR:  relation "goose_db_version" does not exist at character 361151niks3-go-unit-tests> 2026-06-29 09:29:20.149 UTC [1357] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1152niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.51s)1153niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received cleanup request method=DELETE path=/api/pending_closures1154niks3-go-unit-tests> 2026-06-29 09:29:20.149 UTC [1358] ERROR:  relation "goose_db_version" does not exist at character 361155niks3-go-unit-tests> 2026-06-29 09:29:20.149 UTC [1358] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1156niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.52s)1157niks3-go-unit-tests> 2026-06-29 09:29:20.151 UTC [1366] ERROR:  relation "goose_db_version" does not exist at character 361158niks3-go-unit-tests> 2026-06-29 09:29:20.151 UTC [1366] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1159niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.52s)1160niks3-go-unit-tests> 2026-06-29 09:29:20.152 UTC [1376] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-29 09:29:20.152 UTC [1376] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=01163niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1164niks3-go-unit-tests> 2026-06-29 09:29:20.154 UTC [1377] ERROR:  relation "goose_db_version" does not exist at character 361165niks3-go-unit-tests> 2026-06-29 09:29:20.154 UTC [1377] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1166niks3-go-unit-tests> 2026-06-29 09:29:20.154 UTC [1375] ERROR:  relation "goose_db_version" does not exist at character 361167niks3-go-unit-tests> 2026-06-29 09:29:20.154 UTC [1375] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1168niks3-go-unit-tests> 2026-06-29 09:29:20.155 UTC [1384] ERROR:  relation "goose_db_version" does not exist at character 361169niks3-go-unit-tests> 2026-06-29 09:29:20.155 UTC [1384] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1170niks3-go-unit-tests> === NAME  TestClientIntegration1171niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration2155100654/002/store/jj1zd8lhc8ykql9kvnj09lcnjjxypxkf-test-file.txt1172niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1385] ERROR:  relation "goose_db_version" does not exist at character 361173niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1385] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1174niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1386] ERROR:  relation "goose_db_version" does not exist at character 361175niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1386] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1176niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1383] ERROR:  relation "goose_db_version" does not exist at character 361177niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1383] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1178niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1387] ERROR:  relation "goose_db_version" does not exist at character 361179niks3-go-unit-tests> 2026-06-29 09:29:20.157 UTC [1387] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1180niks3-go-unit-tests> 2026-06-29 09:29:20.160 UTC [1388] ERROR:  relation "goose_db_version" does not exist at character 361181niks3-go-unit-tests> 2026-06-29 09:29:20.160 UTC [1388] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1182niks3-go-unit-tests> 2026-06-29 09:29:20.162 UTC [1389] ERROR:  relation "goose_db_version" does not exist at character 361183niks3-go-unit-tests> 2026-06-29 09:29:20.162 UTC [1389] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1184niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received cleanup request method=DELETE path=/api/pending_closures1185niks3-go-unit-tests> 2026-06-29 09:29:20.162 UTC [1392] ERROR:  relation "goose_db_version" does not exist at character 361186niks3-go-unit-tests> 2026-06-29 09:29:20.162 UTC [1392] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1187niks3-go-unit-tests> 2026-06-29 09:29:20.163 UTC [1394] ERROR:  relation "goose_db_version" does not exist at character 361188niks3-go-unit-tests> 2026-06-29 09:29:20.163 UTC [1394] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1189niks3-go-unit-tests> 2026-06-29 09:29:20.163 UTC [1390] ERROR:  relation "goose_db_version" does not exist at character 361190niks3-go-unit-tests> 2026-06-29 09:29:20.163 UTC [1390] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1191niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (12ms)1192niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (11.48ms)1193niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (14.19ms)1194niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=11195niks3-go-unit-tests> 2026-06-29 09:29:20.165 UTC [1395] ERROR:  relation "goose_db_version" does not exist at character 361196niks3-go-unit-tests> 2026-06-29 09:29:20.165 UTC [1395] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1197niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (10.39ms)1198niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.81ms)1199niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1200niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.61ms)1201niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.69ms)1202niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (9.79ms)1203niks3-go-unit-tests> 2026-06-29 09:29:20.168 UTC [1327] ERROR:  Closure does not exist: id=11204niks3-go-unit-tests> 2026-06-29 09:29:20.168 UTC [1327] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1205niks3-go-unit-tests> 2026-06-29 09:29:20.168 UTC [1327] STATEMENT:  -- name: CommitPendingClosure :exec1206niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1207niks3-go-unit-tests> 	1208niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.53s)1209niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (10.07ms)1210niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.26ms)1211niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.68ms)1212niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.5ms)1213niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.05ms)1214niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.66ms)1215niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (20.62ms)1216niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (10.77ms)1217niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (10.47ms)1218niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.54s)1219niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (8.08ms)1220niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (9.98ms)1221niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (8.06ms)1222niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (8.15ms)1223niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (8.3ms)1224niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001225niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (8.13ms)1226niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001227niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.64ms)1228niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (8.32ms)1229niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.88ms)1230niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.8ms)1231niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (12.32ms)1232niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (15.17ms)1233niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.79ms)1234niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (14.41ms)1235niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (10.67ms)1236niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.22ms)1237niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.91ms)1238niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (4.75ms)1239niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001240niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.02ms)1241niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (11.92ms)1242niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.53ms)1243niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (14.13ms)1244niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (14.64ms)1245niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.32ms)1246niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.25ms)1247niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.99ms)1248niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.54ms)1249niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (4.6ms)1250niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001251niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.38ms)1252niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.63ms)1253niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.55ms)1254niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.5ms)1255niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.44ms)1256niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21257niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (5.69ms)1258niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001259niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.13ms)1260niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (5.76ms)1261niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001262niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.96ms)1263niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21264niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.44ms)1265niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (13.82ms)1266niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (3.44ms)1267niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.78ms)1268niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.86ms)1269niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.09ms)1270niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.19ms)1271niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.85ms)1272niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.88ms)1273niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.88ms)1274niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.67ms)1275niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.66ms)1276niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.12ms)1277niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (3.47ms)1278niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21279niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (3.66ms)1280niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (5.65ms)1281niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (5.59ms)1282niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001283niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (6.36ms)1284niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (5.43ms)1285niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.71ms)1286niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21287niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (5.46ms)1288niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (5.45ms)1289niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001290niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.21ms)1291niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.32ms)1292niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.45ms)1293niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (4.31ms)1294niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.5ms)1295niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21296niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (4.41ms)1297niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001298niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (11.9ms)1299niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21300niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (4.22ms)1301niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001302niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20241026095416_initial_model.sql (11.01ms)1303niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (3.62ms)1304niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.79ms)1305niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001306niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.86ms)1307niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001308niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1309niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.55s)1310niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.93ms)1311niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (3.09ms)1312niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.56ms)1313niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.71ms)1314niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.58ms)1315niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (3.63ms)1316niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.58ms)1317niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001318niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001319niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.13ms)1320niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251210153512_drop_unused_gin_index.sql (2.92ms)1321niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.19ms)1322niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001323niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.86ms)1324niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001325niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.8ms)1326niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001327niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (1.83ms)1328niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.32ms)1329niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (1.89ms)1330niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001331niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.01ms)1332niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21333niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.3ms)1334niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001335niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (966.12µs)1336niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21337niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.01ms)1338niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21339niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.02ms)1340niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21341niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.56s)1342niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.63ms)1343niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21344niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (2.03ms)1345niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21346niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.54ms)1347niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)1348niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.56ms)1349niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.41ms)1350niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001351niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.38ms)1352niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.18ms)1353niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.27ms)1354niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.44ms)1355niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (3.23ms)1356niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001357niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (3.32ms)1358niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20251218171726_add_pins.sql (3.37ms)1359niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (946.67µs)1360niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21361niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.07ms)1362niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21363niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.18ms)1364niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21365niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.18ms)1366niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21367niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.14ms)1368niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21369niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.29ms)1370niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21371niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1372niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.14ms)1373niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21374niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1375niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1376niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (1.91ms)1377niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (1.74ms)1378niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=01379niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received cleanup request method=DELETE path=/api/pending_closures1380niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1381niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket301382niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (26.67ms)1383niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001384niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (26.64ms)1385niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21386niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (26.65ms)1387niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21388niks3-go-unit-tests> 2026/06/29 09:29:20 OK   20260628120000_add_object_size_and_stats.sql (28.38ms)1389niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1390niks3-go-unit-tests> 2026/06/29 09:29:20 goose: successfully migrated database to version: 202606281200001391niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1392niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Force mode enabled - objects will be deleted immediately without grace period1393niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1394niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket381395niks3-go-unit-tests> 2026/06/29 09:29:20 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1396niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (5.07ms)1397niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.59s)1398niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=11399niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.21ms)1400niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21401niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket341402niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1403niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created nix-cache-info in bucket bucket=bucket331404niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1405niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1406niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1407niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1408niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1409niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1410niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1411niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1412niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1413niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1414niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1415niks3-go-unit-tests> 2026/06/29 09:29:20 OK   1_commit_pending_closure.sql (2.58ms)1416niks3-go-unit-tests> 2026/06/29 09:29:20 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]1417niks3-go-unit-tests> 2026/06/29 09:29:20 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=01418niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.59s)1419niks3-go-unit-tests> 2026/06/29 09:29:20 OK   2_object_stats_trigger.sql (1.18ms)1420niks3-go-unit-tests> 2026/06/29 09:29:20 goose: up to current file version: 21421niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1422niks3-go-unit-tests> 2026/06/29 09:29:20 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1423niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.59s)1424niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_closures1425niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.60s)1426niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_objects1427niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=multipart_uploads1428niks3-go-unit-tests> 2026/06/29 09:29:20 INFO OIDC auth successful provider=test1429niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Authentication failed token_preview=eyJhbGciOi...mIuLz_CNLg 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]1430niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=closures1431niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.60s)1432niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.59s)1433niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1434niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1435niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1436niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1437niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=objects1438niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.61s)1439niks3-go-unit-tests> --- PASS: TestGCMetrics (0.61s)1440niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1441niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:20.249300238Z","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(769)"}1442niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:20.249327908Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket32, 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(769)"}1443niks3-go-unit-tests> 2026/06/29 09:29:20 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=N2ZmZjk2YzEtNmM2NS00NWViLWIzNWUtODY1NDE0MzIxNWI2LmU3MThkYmUzLTc3MGEtNGNmOC1iMWJkLTZiYWQxNTEyZGNiNHgxNzgyNzI1MzYwMjMzNzQwNTMw1444niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=N2ZmZjk2YzEtNmM2NS00NWViLWIzNWUtODY1NDE0MzIxNWI2LmU3MThkYmUzLTc3MGEtNGNmOC1iMWJkLTZiYWQxNTEyZGNiNHgxNzgyNzI1MzYwMjMzNzQwNTMw parts=11445niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.61s)1446niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1447niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1448niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1449niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading jj1zd8lhc8ykql9kvnj09lcnjjxypxkf-test-file.txt (152B)1450niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1451niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading slx5qrnqglhrap80alivsj28sb4pax8a-file1.txt (160B)1452niks3-go-unit-tests> === NAME  TestClientMultipleUploads1453niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads3336457677/001/store/qa6a04bdx839qzhcmn0gs0027h0z8d2y-test-file-0.txt1454niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1455niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1456niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11457niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11458niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1459niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1460niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1461niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1462niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11463niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (114ms)1464niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11465niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (96ms)1466niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1467niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1468niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug867243446/001/store/slx5qrnqglhrap80alivsj28sb4pax8a-file1.txt1469niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1470niks3-go-unit-tests>         Compression: zstd1471niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1472niks3-go-unit-tests>         NarSize: 1601473niks3-go-unit-tests>         References: 1474niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1475niks3-go-unit-tests> === NAME  TestClientIntegration1476niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1477niks3-go-unit-tests>         StorePath: /build/TestClientIntegration2155100654/002/store/jj1zd8lhc8ykql9kvnj09lcnjjxypxkf-test-file.txt1478niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1479niks3-go-unit-tests>         Compression: zstd1480niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11481niks3-go-unit-tests>         NarSize: 1521482niks3-go-unit-tests>         References: 1483niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11484niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1485niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC1801092741/001/store/jzkmrpdnxlnvbnf2hd2s3xkyqgpwxwnd-pinned-file.txt1486niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC1801092741/001/store/12yykbiiinhim1s2l0r4zlvpf2gsnxhr-unpinned-file.txt1487niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1488niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1489niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1490niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1491niks3-go-unit-tests> === NAME  TestClientIntegration1492niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1493niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1494niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1495niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1496niks3-go-unit-tests> === NAME  TestClientMultipleUploads1497niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads3336457677/001/store/d5lv29bfhjgqhsjajvyb1sd800siwv5h-test-file-1.txt1498niks3-go-unit-tests> === NAME  TestClientWithDependencies1499niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies1883214570/001/store/2adv66slprbf2lvhwlpj2d791lfm4ax2-test-script1500niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1501niks3-go-unit-tests> === NAME  TestClientCADerivations1502niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations3693376475/001/store/pclp4rlwgmrdrawbg9blzv7fllqg2r6j-ca-test1503niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Starting cleanup of old closures method=DELETE path=/api/closures1504niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Garbage collection started1505niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1506niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug867243446/001/store/686y915ql5xmvjpliiwn2y0s4c04lggg-file2.txt1507niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=01508niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Force mode enabled - objects will be deleted immediately without grace period1509niks3-go-unit-tests> === NAME  TestClientMultipleUploads1510niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads3336457677/001/store/fr3m5qy1dmk6yamg03qa8rl3x4scqsas-test-file-2.txt1511niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1512niks3-go-unit-tests> === NAME  TestClientWithDependencies1513niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1514niks3-go-unit-tests> === NAME  TestClientCADerivations1515niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1516niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1517niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1518niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading jzkmrpdnxlnvbnf2hd2s3xkyqgpwxwnd-pinned-file.txt (128B)1519niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.75s)1520niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.76s)1521niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1522niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11523niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1524niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1525niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1526niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11527niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (82ms)1528niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1529niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 2adv66slprbf2lvhwlpj2d791lfm4ax2-test-script (136B)1530niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1531niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11532niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1533niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1534niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1535niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11536niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (46ms)1537niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1538niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1539niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1540niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1541niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1542niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1543niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1544niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.79s)1545niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1546niks3-go-unit-tests> === NAME  TestClientWithDependencies1547niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies1883214570/001/store) requires matching store prefix1548niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1549niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=2 count=11550niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1551niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1552niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1553niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=21554niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (69ms)1555niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1556niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1557niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug867243446/001/store/686y915ql5xmvjpliiwn2y0s4c04lggg-file2.txt1558niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1559niks3-go-unit-tests>         Compression: zstd1560niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1561niks3-go-unit-tests>         NarSize: 1601562niks3-go-unit-tests>         References: 1563niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1564niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.79s)1565niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1566niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1567niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1568niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1569niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1570niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1571niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.79s)1572niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1573niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading d5lv29bfhjgqhsjajvyb1sd800siwv5h-test-file-1.txt (160B)1574niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading fr3m5qy1dmk6yamg03qa8rl3x4scqsas-test-file-2.txt (160B)1575niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading qa6a04bdx839qzhcmn0gs0027h0z8d2y-test-file-0.txt (160B)1576niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.80s)1577niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1578niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1579niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11580niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1581niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=2 count=11582niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1583niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=3 count=11584niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 3 narinfos1585niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1586niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading pclp4rlwgmrdrawbg9blzv7fllqg2r6j-ca-test (144B)1587niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1588niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1589niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=1 count=11590niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1591niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11592niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1593niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1594niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=21595niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1596niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=31597niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (83ms)1598niks3-go-unit-tests> === NAME  TestClientMultipleUploads1599niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 114.229594ms1600niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11601niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (77ms)1602niks3-go-unit-tests> === NAME  TestClientCADerivations1603niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations3693376475/001/store/pclp4rlwgmrdrawbg9blzv7fllqg2r6j-ca-test1604niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1605niks3-go-unit-tests>         Compression: zstd1606niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1607niks3-go-unit-tests>         NarSize: 1441608niks3-go-unit-tests>         References: 1609niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations3693376475/001/store/c8gn51iydjix7cq2f557njh0nq3riaja-ca-test.drv1610niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1611niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1612niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1613niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1614niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1615niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.82s)1616niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=N2ZmZjk2YzEtNmM2NS00NWViLWIzNWUtODY1NDE0MzIxNWI2LjJhYjkyNTUwLWMwMjYtNDkwMS04ZmZkLTdhYjNiYmM2MTBkY3gxNzgyNzI1MzYwMDg0NTM0Njky parts=121617niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.83s)1618niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1619niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=742.179235ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1620niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=N2ZmZjk2YzEtNmM2NS00NWViLWIzNWUtODY1NDE0MzIxNWI2LmYxMWFhYTYxLTA1YjgtNDhhZC1iNTkwLWM0YTk2ZmZjM2ZiMXgxNzgyNzI1MzYwMjM0MDkzOTgw parts=101621niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1622niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1623niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11624niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1625niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1626niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=N2ZmZjk2YzEtNmM2NS00NWViLWIzNWUtODY1NDE0MzIxNWI2LmEyNWRiYmJlLWU2M2YtNDFhYy05MzIzLTRiODRmMGQxODNiYngxNzgyNzI1MzYwMjMxOTk3MTUy parts=101627niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1628niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1629niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Found objects in DB but missing from S3, will re-upload count=11630niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.85s)1631niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=11632niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001633niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1634niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Starting cleanup of old closures method=DELETE path=/api/closures1635niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received uploads request method=POST path=/api/pending_closures1636niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.17s)1637niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.04s)1638niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1639niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.69s)1640niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1641niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 12yykbiiinhim1s2l0r4zlvpf2gsnxhr-unpinned-file.txt (128B)1642niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=01643niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1644niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Signed narinfos id=2 count=11645niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Uploading 1 narinfos1646niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1647niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Completed upload id=21648niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Upload complete. (70ms)1649niks3-go-unit-tests> 2026/06/29 09:29:20 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=01650niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_closures1651niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_objects1652niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=multipart_uploads1653niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=closures1654niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=objects1655niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received create pin request method=POST path=/api/pins/myapp1656niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC1801092741/001/store/jzkmrpdnxlnvbnf2hd2s3xkyqgpwxwnd-pinned-file.txt narinfo_key=jzkmrpdnxlnvbnf2hd2s3xkyqgpwxwnd.narinfo1657niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Starting cleanup of old closures method=DELETE path=/api/closures1658niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Garbage collection started1659niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001660niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.91s)1661niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Aborted multipart uploads count=01662niks3-go-unit-tests> 2026/06/29 09:29:20 WARN Force mode enabled - objects will be deleted immediately without grace period1663niks3-go-unit-tests> === NAME  TestClientCADerivations1664niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1665niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1666niks3-go-unit-tests>         error: binary cache 's3://bucket34?endpoint=http://localhost:43683&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations3693376475/001/store'1667niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11668niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.95s)1669niks3-go-unit-tests> 2026/06/29 09:29:20 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=01670niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_closures1671niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_objects1672niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=multipart_uploads1673niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=closures1674niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=objects1675niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1676niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1677niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1678niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1679niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201680niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101681niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101682niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.20s)1683niks3-go-unit-tests> 2026/06/29 09:29:20 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=01684niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_closures1685niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=pending_objects1686niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=multipart_uploads1687niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=closures1688niks3-go-unit-tests> 2026/06/29 09:29:20 INFO Vacuumed table table=objects1689niks3-go-unit-tests> 2026/06/29 09:29:21 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.673795085s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1690niks3-go-unit-tests> 2026/06/29 09:29:22 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01691niks3-go-unit-tests> === NAME  TestClientIntegration1692niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1693niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1694niks3-go-unit-tests> --- PASS: TestClientIntegration (2.70s)1695niks3-go-unit-tests> 2026/06/29 09:29:22 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01696niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1697niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1698niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.91s)1699niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1700niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.59s)1701niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.70s)1702niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.26s)1703niks3-go-unit-tests> 2026/06/29 09:29:23 WARN Rate limiter enabled after throttle name=s3-test rate=51704niks3-go-unit-tests> 2026/06/29 09:29:23 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1705niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1706niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101707niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001708niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.08s)1709niks3-go-unit-tests> PASS1710niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:24.226084029Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:41090"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(757)"}1711niks3-go-unit-tests> 2026-06-29 09:29:24.339 UTC [309] LOG:  received smart shutdown request1712niks3-go-unit-tests> 2026-06-29 09:29:24.342 UTC [309] LOG:  background worker "logical replication launcher" (PID 316) exited with exit code 11713niks3-go-unit-tests> 2026-06-29 09:29:24.345 UTC [311] LOG:  shutting down1714niks3-go-unit-tests> 2026-06-29 09:29:24.346 UTC [311] LOG:  checkpoint starting: shutdown immediate1715niks3-go-unit-tests> 2026-06-29 09:29:25.879 UTC [311] LOG:  checkpoint complete: wrote 10122 buffers (61.8%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.156 s, sync=1.367 s, total=1.535 s; sync files=14421, longest=0.011 s, average=0.001 s; distance=194058 kB, estimate=194058 kB; lsn=0/D26F390, redo lsn=0/D26F3901716niks3-go-unit-tests> 2026-06-29 09:29:25.955 UTC [309] LOG:  database system is shut down1717niks3-go-unit-tests> Running OIDC tests...1718niks3-go-unit-tests> === RUN   TestGlobMatch1719niks3-go-unit-tests> === PAUSE TestGlobMatch1720niks3-go-unit-tests> === RUN   TestAudienceForIssuer1721niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1722niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1723niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1724niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1725niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1726niks3-go-unit-tests> === RUN   TestValidateToken_Expired1727niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1728niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1729niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1730niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1731niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1732niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1733niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1734niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1735niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1736niks3-go-unit-tests> === CONT  TestGlobMatch1737niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1738niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1739niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1740niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1741niks3-go-unit-tests> === CONT  TestAudienceForIssuer1742niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1743niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1744niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1745niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1746niks3-go-unit-tests> === CONT  TestValidateToken_Expired1747niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1748niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1749niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1750niks3-go-unit-tests> === RUN   TestGlobMatch/*_1751niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1752niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1753niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1754niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1755niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1756niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1757niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1758niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1759niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1760niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1761niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1762niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1763niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1764niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1765niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1766niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1767niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1768niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1769niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1770niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1771niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1772niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1773niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1774niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1775niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1776niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1777niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1778niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01779niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01780niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1781niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1782niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1783niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1784niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1785niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1786niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1787niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1788niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1789niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1790niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1791niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1792niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1793niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1794niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1795niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1796niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1797niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1798niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1799niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1800niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1801niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1802niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1803niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1804niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01805niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1806niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1807niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1808niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1809niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1810niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1811niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1812niks3-go-unit-tests> === CONT  TestGlobMatch/*_1813niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1814niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1815niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1816niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1817niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1818niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1819niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1820niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1821niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1842niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1843niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1844niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1845niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1846niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1847niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=test1848niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=provider11849niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=test1850niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=test1851niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=test1852niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=test1853niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=provider11854niks3-go-unit-tests> 2026/06/29 09:29:26 INFO OIDC provider initialized name=provider21855niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1856niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1857niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1858niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1859niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1860niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1861niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1862niks3-go-unit-tests> PASS1863niks3-go-unit-tests> Running hook tests...1864niks3-go-unit-tests> === RUN   TestSendPathsEmpty1865niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1866niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1867niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1868niks3-go-unit-tests> === RUN   TestQueueDeduplication1869niks3-go-unit-tests> === PAUSE TestQueueDeduplication1870niks3-go-unit-tests> === RUN   TestQueueRemove1871niks3-go-unit-tests> === PAUSE TestQueueRemove1872niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1873niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1874niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1875niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1876niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1877niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1878niks3-go-unit-tests> === RUN   TestServerClientIntegration1879niks3-go-unit-tests> === PAUSE TestServerClientIntegration1880niks3-go-unit-tests> === RUN   TestServerQueueError1881niks3-go-unit-tests> === PAUSE TestServerQueueError1882niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1883niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1884niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1885niks3-go-unit-tests>         PASS1886niks3-go-unit-tests>         1887niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.03s)1888niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1889niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1890niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1891niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1892niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1893niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1894niks3-go-unit-tests> === CONT  TestSendPathsEmpty1895niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1896niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1897niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1898niks3-go-unit-tests> === CONT  TestQueueRemove1899niks3-go-unit-tests> === CONT  TestServerClientIntegration1900niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1901niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1902niks3-go-unit-tests> === CONT  TestQueueDeduplication1903niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1904niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1905niks3-go-unit-tests> === CONT  TestServerQueueError1906niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1907niks3-go-unit-tests> 2026/06/29 09:29:26 ERROR Failed to queue paths error="permission denied" count=11908niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1909niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1910niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Upload queue status pending=21911niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Uploading batch count=11912niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1913niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1914niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1915niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Upload queue status pending=21916niks3-go-unit-tests> 2026/06/29 09:29:26 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths3491171914/002/nonexistent1917niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Upload queue status pending=21918niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Uploading batch count=21919niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1920niks3-go-unit-tests> 2026/06/29 09:29:26 INFO Uploading batch count=11921niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1922niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1923niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1924niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1925niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.27s)1926niks3-go-unit-tests> PASS19271928post-build step Upload coverage to codecov: ok1929Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests