nixbot

1this derivation will be built:2  /nix/store/vibygy3fsrqpicf8jasczswrjni2znh2-niks3-go-unit-tests.drv3building '/nix/store/vibygy3fsrqpicf8jasczswrjni2znh2-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/vibygy3fsrqpicf8jasczswrjni2znh2-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestConvertHashToNix3276niks3-go-unit-tests> === CONT  TestParsePathInfoJSON77niks3-go-unit-tests> === CONT  TestResolveStorePath78niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3279niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3280niks3-go-unit-tests> === CONT  TestFileTokenMissing81niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches82niks3-go-unit-tests> === CONT  TestStaticToken83niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand84niks3-go-unit-tests> === CONT  TestSetClientTLSErrors85niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails86niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport87niks3-go-unit-tests> === CONT  TestDumpPathSingleFile88niks3-go-unit-tests> === CONT  TestSetClientTLS89niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON90niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash91niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken92niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file93niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file94niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file95niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file96niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file97niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file98niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file99niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file100niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file101niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file102niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file103niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file104niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh105niks3-go-unit-tests> === CONT  TestEncodeNixBase32106niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash107niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash108niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall109niks3-go-unit-tests> === CONT  TestDumpPathWriterError110niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths111niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess112niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths113niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths114niks3-go-unit-tests> === CONT  TestFileTokenEmpty115niks3-go-unit-tests> === CONT  TestRateLimiterFeedback116niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer117niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility118niks3-go-unit-tests> === CONT  TestShellSplitErrors119niks3-go-unit-tests> === CONT  TestShellSplit120niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility121niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody122niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix123niks3-go-unit-tests> === CONT  TestGetStorePathHash124niks3-go-unit-tests> === CONT  TestPartSizeForNAR125niks3-go-unit-tests> === CONT  TestCaseHackSuffix126niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format127niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format128niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)129niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input130niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field131niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input132niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)133niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum134niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format135niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)136niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter enabled after throttle name=server-test rate=5137niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format138niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert139niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists140niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists141niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths142niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash143niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input144niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field145niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)146niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)147niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path148niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter149niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum150niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format151niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)152niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path153niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)154niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format155niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert156niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum157niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA158niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text159niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text160niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter enabled after throttle name=server-test rate=5161niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon162niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41351163niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format164niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)165niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error166niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)167niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing168niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)169niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32170niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)171niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)172niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)173niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)174niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)175niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format176niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error177niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format178niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter backed off name=server-test rate=5179niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter180niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41351181niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths182niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter183niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum184niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter185niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA186niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter187niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter188niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport189niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter190niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport191niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter192niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert193niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive194niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA195niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive196niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport197niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text198niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text199niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method200niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method201niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format202niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text203niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input204niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input205niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing206niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only207niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists208niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing209niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error210niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths211niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths212niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum213niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter214niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter215niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter216niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter217niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon218niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field219niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method220niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text221niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI222niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive223niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)224niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only225niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error226niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum227niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts228niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts229niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)230niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)231niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)232niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI233niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON234niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)235niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)236niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)237niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)238niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)239niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)240niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)241niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)242niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)243niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON244niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format245niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only246niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input247niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)248niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.01s)249niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB250niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512251niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB252niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512253niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter enabled after throttle name=server-test rate=5254niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error255niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI256niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512257niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:41025258niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter enabled after throttle name=server-test rate=5259niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)260niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)261niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)262niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)263niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)264niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)265niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON266niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:37009267niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)268niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)269niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)270niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object271niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon272niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)273niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter backed off name=server-test rate=5274niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)275niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error276niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.02s)277niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)278niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)279niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)280niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)281niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error282niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format283niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object284niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.02s)285niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)286niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)287niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)288niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)289niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)290niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path291niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB292niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error293niks3-go-unit-tests> 2026/06/29 09:14:12 WARN Rate limiter backed off name=server-test rate=5294niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error295niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB296niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.02s)297niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)298niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)299niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)300niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)301niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object302niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum303niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts304niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB305niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB306niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum307niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum308niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.02s)309niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)310niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)311niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)312niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)313niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)314niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)315niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)316niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.01s)317niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)318niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)319niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)320niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)321niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)322niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)323niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)324niks3-go-unit-tests> 2026/06/29 09:14:12 http: TLS handshake error from 127.0.0.1:54504: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.01s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)330niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.07s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.12s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres657941575/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/postgres657941575/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres657941575:5432 - no response364niks3-go-unit-tests> 2026-06-29 09:14:13.826 UTC [293] 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:14:13.826 UTC [293] LOG:  listening on Unix socket "/build/postgres657941575/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-29 09:14:13.831 UTC [297] LOG:  database system was shut down at 2026-06-29 09:14:13 UTC367niks3-go-unit-tests> 2026-06-29 09:14:13.835 UTC [293] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres657941575:5432 - accepting connections369niks3-go-unit-tests> === RUN   TestService_AuthMiddleware370niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects375niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware376niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware377niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC378niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC379niks3-go-unit-tests> === RUN   TestCacheConfigHandler380niks3-go-unit-tests> === PAUSE TestCacheConfigHandler381niks3-go-unit-tests> === RUN   TestCacheStatsHandler382niks3-go-unit-tests> === PAUSE TestCacheStatsHandler383niks3-go-unit-tests> === RUN   TestClientCADerivations384niks3-go-unit-tests> === PAUSE TestClientCADerivations385niks3-go-unit-tests> === RUN   TestClientErrorHandling386niks3-go-unit-tests> === PAUSE TestClientErrorHandling387niks3-go-unit-tests> === RUN   TestClientIntegration388niks3-go-unit-tests> === PAUSE TestClientIntegration389niks3-go-unit-tests> === RUN   TestClientMultipleUploads390niks3-go-unit-tests> === PAUSE TestClientMultipleUploads391niks3-go-unit-tests> === RUN   TestClientWithDependencies392niks3-go-unit-tests> === PAUSE TestClientWithDependencies393niks3-go-unit-tests> === RUN   TestPinProtectsFromGC394niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC395niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun396niks3-go-unit-tests> {"timestamp":"2026-06-29T09:14:14.241358394Z","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(763)"}397niks3-go-unit-tests> 398niks3-go-unit-tests> thread 'rustfs-worker' (949) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:399niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }400niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace401niks3-go-unit-tests> 2026-06-29 09:14:14.288 UTC [1093] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-29 09:14:14.288 UTC [1093] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20241026095416_initial_model.sql (5.24ms)404niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20251210153512_drop_unused_gin_index.sql (2.35ms)405niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20251218171726_add_pins.sql (2.13ms)406niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20260628120000_add_object_size_and_stats.sql (2.04ms)407niks3-go-unit-tests> 2026/06/29 09:14:14 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/29 09:14:14 OK   1_commit_pending_closure.sql (1.7ms)409niks3-go-unit-tests> 2026/06/29 09:14:14 OK   2_object_stats_trigger.sql (804.56µs)410niks3-go-unit-tests> 2026/06/29 09:14:14 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.09s)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:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/29 09:14:14 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/29 09:14:14 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  TestResurrectedObjectNotDeleted525niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed526niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC527niks3-go-unit-tests> === CONT  TestIsValidCachePath528niks3-go-unit-tests> === CONT  TestClientErrorHandling529niks3-go-unit-tests> === CONT  TestCacheStatsHandler530niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest531niks3-go-unit-tests> === CONT  TestClientIntegration532niks3-go-unit-tests> === CONT  TestReadProxyHead533niks3-go-unit-tests> === CONT  TestPinProtectsFromGC534niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader535niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences536niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet537niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML538niks3-go-unit-tests> === CONT  TestReadProxy404539niks3-go-unit-tests> === CONT  TestGCMetrics540niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming541niks3-go-unit-tests> === CONT  TestObjectStatsTrigger542niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo543niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew544niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo545niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)546niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug547niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars548niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars549niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst550niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst551niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz552niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz553niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2554niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2555niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed556niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed557niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls558niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls559niks3-go-unit-tests> === RUN   TestIsValidCachePath/log560niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log561niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation562niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation563niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info564niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info565niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html566niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html567niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent568niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent569niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle570niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle571niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e572niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e573niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u574niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u575niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path576niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path577niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty578niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty579niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash580niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash581niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension582niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension583niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash584niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash585niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo586niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash587niks3-go-unit-tests> === CONT  TestGenerateLandingPage588niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path589niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u590niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e591niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty592niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent593niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension594niks3-go-unit-tests> === CONT  TestService_healthCheckHandler595niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html596niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail597niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)598niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates599niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)600niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask601niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)602niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest603niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)604niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty605niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)606niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams607niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)608niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams609niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)610niks3-go-unit-tests> === CONT  TestReadProxyDisabled611niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT612niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight613niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered614niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity615niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler616niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody617niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys618niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info619niks3-go-unit-tests> === CONT  TestIsValidUploadKey620niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info621niks3-go-unit-tests> === CONT  TestProxyWriteTimeout622niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Starting HTTP server address=127.0.0.1:35347623niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle624niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo625niks3-go-unit-tests> === CONT  TestService_Rustfstest626niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists627niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload628niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest629niks3-go-unit-tests> === CONT  TestReadProxyNarinfo630niks3-go-unit-tests> === CONT  TestParseSingleRange631niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC632niks3-go-unit-tests> === CONT  TestClientCADerivations633niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath634niks3-go-unit-tests> === CONT  TestClientWithDependencies635niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects636niks3-go-unit-tests> === CONT  TestClientMultipleUploads637niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware638niks3-go-unit-tests> === CONT  TestCacheConfigHandler639niks3-go-unit-tests> === CONT  TestServerTLSConfig640niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath641niks3-go-unit-tests> === CONT  TestService_NativeMTLS642niks3-go-unit-tests> === CONT  TestMetricsInventory643niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info644niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation645niks3-go-unit-tests> === CONT  TestIsValidCachePath/log646niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls647niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed648niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2649niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz650niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst651niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars652niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash653niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle654niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler655niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal656niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo657niks3-go-unit-tests> === RUN   TestParseSingleRange/none658niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer659niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst660niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst661niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer662niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA663niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA664niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file665niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo666niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath667niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo668niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal669niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key670niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar671niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key672niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar673niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key674niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar675niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key676niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar677niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal678niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key679niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size680niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size681niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo682niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size683niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)684niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)685niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)686niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)687niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)688niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)689niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)690niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)691niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)692niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)693niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)694niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)695niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)696niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)697niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)698niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)699niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)700niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)701niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)702niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)703niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)704niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar705niks3-go-unit-tests> === PAUSE TestParseSingleRange/none706niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz707niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit708niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz709niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file710niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured711niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Shutdown signal received, draining in-flight requests timeout=10s712niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken713niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured714niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info715niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key716niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar717niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)718niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit719niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.01s)720niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)721niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)722niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)723niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)724niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain725niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file726niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain727niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file728niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken729niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing730niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing731niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys732niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA733niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received request for more parts method=POST path=/734niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received uploads request method=POST path=/735niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys736niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log737niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received uploads request method=POST path=/738niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator739niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received complete multipart upload request method=POST path=/740niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log741niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator742niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file743niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer744niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys745niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator746niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored747niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored748niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash749niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash750niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file751niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file752niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured753niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty754niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable755niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty756niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable757niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath758niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start759niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file760niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable761niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name762niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken763niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name764niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark765niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)766niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)767niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)768niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)769niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)770niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start771niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark772niks3-go-unit-tests> === RUN   TestParseSingleRange/closed773niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals774niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed775niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals776niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended777niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended778niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation779niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation780niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size781niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output782niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output783niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info784niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)785niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)786niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)787niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)788niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)789niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size790niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix791niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info792niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix793niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html794niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html795niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size796niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size797niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type798niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte799niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type800niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte801niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type802niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF803niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type804niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF805niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type806niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF807niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type808niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF809niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal810niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)811niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)812niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)813niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)814niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit815niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF816niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size817niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix818niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size819niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte820niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended821niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start822niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty823niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash824niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored825niks3-go-unit-tests> === CONT  TestParseSingleRange/none826niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF827niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal828niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar829niks3-go-unit-tests> === CONT  TestParseSingleRange/closed830niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar831niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)832niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)833niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)834niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)835niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)836niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)837niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)838niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)839niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)840niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)841niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)842niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)843niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)844niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)845niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)846niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute847niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute848niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key849niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key850niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type851niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type852niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo853niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output854niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file855niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst856niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals857niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info858niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type859niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing860niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain861niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz862niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal863niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type864niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key865niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute866niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type867niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html868niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar869niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type870niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log871niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark872niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name873niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation874niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.01s)875niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)876niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)877niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)878niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)896niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)897niks3-go-unit-tests> 2026/06/29 09:14:14 INFO OIDC provider initialized name=test898niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.08s)899niks3-go-unit-tests> 2026/06/29 09:14:14 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_closures900niks3-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> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart909niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received uploads request method=POST path=/910niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received request for more parts method=POST path=/911niks3-go-unit-tests> 2026/06/29 09:14:14 INFO Received complete multipart upload request method=POST path=/912niks3-go-unit-tests> 2026-06-29 09:14:14.745 UTC [1281] ERROR:  relation "goose_db_version" does not exist at character 36913niks3-go-unit-tests> 2026-06-29 09:14:14.745 UTC [1281] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC914niks3-go-unit-tests> 2026-06-29 09:14:14.749 UTC [1283] ERROR:  relation "goose_db_version" does not exist at character 36915niks3-go-unit-tests> 2026-06-29 09:14:14.749 UTC [1283] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC916niks3-go-unit-tests> 2026-06-29 09:14:14.750 UTC [1299] ERROR:  relation "goose_db_version" does not exist at character 36917niks3-go-unit-tests> 2026-06-29 09:14:14.750 UTC [1299] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC918niks3-go-unit-tests> 2026-06-29 09:14:14.750 UTC [1300] ERROR:  relation "goose_db_version" does not exist at character 36919niks3-go-unit-tests> 2026-06-29 09:14:14.750 UTC [1300] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC920niks3-go-unit-tests> 2026-06-29 09:14:14.751 UTC [1301] ERROR:  relation "goose_db_version" does not exist at character 36921niks3-go-unit-tests> 2026-06-29 09:14:14.751 UTC [1301] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC922niks3-go-unit-tests> 2026-06-29 09:14:14.767 UTC [1302] ERROR:  relation "goose_db_version" does not exist at character 36923niks3-go-unit-tests> 2026-06-29 09:14:14.767 UTC [1302] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC924niks3-go-unit-tests> 2026-06-29 09:14:14.788 UTC [1303] ERROR:  relation "goose_db_version" does not exist at character 36925niks3-go-unit-tests> 2026-06-29 09:14:14.788 UTC [1303] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC926niks3-go-unit-tests> 2026/06/29 09:14:14 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=205.54024ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures927niks3-go-unit-tests> 2026-06-29 09:14:14.830 UTC [1304] ERROR:  relation "goose_db_version" does not exist at character 36928niks3-go-unit-tests> 2026-06-29 09:14:14.830 UTC [1304] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC929niks3-go-unit-tests> 2026-06-29 09:14:14.835 UTC [1305] ERROR:  relation "goose_db_version" does not exist at character 36930niks3-go-unit-tests> 2026-06-29 09:14:14.835 UTC [1305] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC931niks3-go-unit-tests> 2026-06-29 09:14:14.838 UTC [1306] ERROR:  relation "goose_db_version" does not exist at character 36932niks3-go-unit-tests> 2026-06-29 09:14:14.838 UTC [1306] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC933niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20241026095416_initial_model.sql (153.48ms)934niks3-go-unit-tests> 2026/06/29 09:14:14 OK   20251210153512_drop_unused_gin_index.sql (4.69ms)935niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=381.218265ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures936niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (217.71ms)937niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (217.78ms)938niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (6.33ms)939niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (224.78ms)940niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (61.26ms)941niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (224.38ms)942niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (6.11ms)943niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (212.3ms)944niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (5.67ms)945niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (5.77ms)946niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (218.52ms)947niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (16.63ms)948niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (22.05ms)949niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000950niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (26.41ms)951niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (28.54ms)952niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (137.11ms)953niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (9.94ms)954niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (28.26ms)955niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (9.08ms)956niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000957niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (128.47ms)958niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (14.01ms)959niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (27.97ms)960niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (7.06ms)961niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (6.34ms)962niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 2963niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (19.9ms)964niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (6.68ms)965niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (8.88ms)966niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (133.93ms)967niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (5.66ms)968niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (7.67ms)969niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 2970niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (18.06ms)971niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000972niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (25.08ms)973niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000974niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (27.28ms)975niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (24.85ms)976niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (13.47ms)977niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (34.92ms)978niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000979niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (25.3ms)980niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 20260628120000981niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (31.45ms)982niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (4.95ms)983niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 2984niks3-go-unit-tests> {"timestamp":"2026-06-29T09:14:15.097540998Z","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(493)"}985niks3-go-unit-tests> 2026-06-29 09:14:15.097 UTC [1308] ERROR:  relation "goose_db_version" does not exist at character 36986niks3-go-unit-tests> 2026-06-29 09:14:15.097 UTC [1308] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC987niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (7.34ms)988niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (7.33ms)989niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (12.24ms)990niks3-go-unit-tests> 2026-06-29 09:14:15.099 UTC [1309] ERROR:  relation "goose_db_version" does not exist at character 36991niks3-go-unit-tests> 2026-06-29 09:14:15.099 UTC [1309] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC992niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.55s)993niks3-go-unit-tests> 2026-06-29 09:14:15.100 UTC [1310] ERROR:  relation "goose_db_version" does not exist at character 36994niks3-go-unit-tests> 2026-06-29 09:14:15.100 UTC [1310] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC995niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (27.63ms)996niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (6.43ms)997niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 2998niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (6.49ms)999niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (6.16ms)1000niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21001niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (14.05ms)1002niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001003niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21004niks3-go-unit-tests> 2026-06-29 09:14:15.105 UTC [1311] ERROR:  relation "goose_db_version" does not exist at character 361005niks3-go-unit-tests> 2026-06-29 09:14:15.105 UTC [1311] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1006niks3-go-unit-tests> 2026-06-29 09:14:15.106 UTC [1312] ERROR:  relation "goose_db_version" does not exist at character 361007niks3-go-unit-tests> 2026-06-29 09:14:15.106 UTC [1312] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1008niks3-go-unit-tests> 2026-06-29 09:14:15.107 UTC [1314] ERROR:  relation "goose_db_version" does not exist at character 361009niks3-go-unit-tests> 2026-06-29 09:14:15.107 UTC [1314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1010niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (17.29ms)1011niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (17.47ms)1012niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001013niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001014niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1015niks3-go-unit-tests> 2026-06-29 09:14:15.108 UTC [1315] ERROR:  relation "goose_db_version" does not exist at character 361016niks3-go-unit-tests> 2026-06-29 09:14:15.108 UTC [1315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1017niks3-go-unit-tests> 2026-06-29 09:14:15.109 UTC [1313] ERROR:  relation "goose_db_version" does not exist at character 361018niks3-go-unit-tests> 2026-06-29 09:14:15.109 UTC [1313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1019niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1020niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.56s)1021niks3-go-unit-tests> 2026-06-29 09:14:15.109 UTC [1316] ERROR:  relation "goose_db_version" does not exist at character 361022niks3-go-unit-tests> 2026-06-29 09:14:15.109 UTC [1316] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1023niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.56s)1024niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (7.07ms)1025niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (11.17ms)1026niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (8.45ms)1027niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (36.88ms)1028niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001029niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (23.88ms)1030niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (24.19ms)1031niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21032niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (24.23ms)1033niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21034niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21035niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (6.61ms)1036niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.59s)1037niks3-go-unit-tests> 2026-06-29 09:14:15.152 UTC [1325] ERROR:  relation "goose_db_version" does not exist at character 361038niks3-go-unit-tests> 2026-06-29 09:14:15.152 UTC [1325] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1039niks3-go-unit-tests> 2026-06-29 09:14:15.153 UTC [1327] ERROR:  relation "goose_db_version" does not exist at character 361040niks3-go-unit-tests> 2026-06-29 09:14:15.153 UTC [1327] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1041niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.60s)1042niks3-go-unit-tests> 2026-06-29 09:14:15.153 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 361043niks3-go-unit-tests> 2026-06-29 09:14:15.153 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1044niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (9.4ms)1045niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21046niks3-go-unit-tests> 2026-06-29 09:14:15.156 UTC [1330] ERROR:  relation "goose_db_version" does not exist at character 361047niks3-go-unit-tests> 2026-06-29 09:14:15.156 UTC [1330] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1048niks3-go-unit-tests> 2026-06-29 09:14:15.157 UTC [1331] ERROR:  relation "goose_db_version" does not exist at character 361049niks3-go-unit-tests> 2026-06-29 09:14:15.157 UTC [1331] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1050niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.60s)1051niks3-go-unit-tests> 2026-06-29 09:14:15.159 UTC [1332] ERROR:  relation "goose_db_version" does not exist at character 361052niks3-go-unit-tests> 2026-06-29 09:14:15.159 UTC [1332] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1053niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket111054niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (42.6ms)1055niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (20.69ms)1056niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (21.17ms)1057niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (22.19ms)1058niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.66ms)1059niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (24.43ms)1060niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.45ms)1061niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.06ms)1062niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1334] ERROR:  relation "goose_db_version" does not exist at character 361063niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1334] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1064niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1335] ERROR:  relation "goose_db_version" does not exist at character 361065niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1335] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1066niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1333] ERROR:  relation "goose_db_version" does not exist at character 361067niks3-go-unit-tests> 2026-06-29 09:14:15.170 UTC [1333] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1068niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (26.9ms)1069niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (26.56ms)1070niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (26.91ms)1071niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (4.26ms)1072niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.32ms)1073niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (28.42ms)1074niks3-go-unit-tests> 2026-06-29 09:14:15.173 UTC [1337] ERROR:  relation "goose_db_version" does not exist at character 361075niks3-go-unit-tests> 2026-06-29 09:14:15.173 UTC [1337] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1076niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.9ms)1077niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.75ms)1078niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (4.95ms)1079niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (6.93ms)1080niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.98ms)1081niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.71ms)1082niks3-go-unit-tests> 2026-06-29 09:14:15.175 UTC [1338] ERROR:  relation "goose_db_version" does not exist at character 361083niks3-go-unit-tests> 2026-06-29 09:14:15.175 UTC [1338] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1084niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (4.44ms)1085niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.19ms)1086niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.31ms)1087niks3-go-unit-tests> 2026-06-29 09:14:15.178 UTC [1339] ERROR:  relation "goose_db_version" does not exist at character 361088niks3-go-unit-tests> 2026-06-29 09:14:15.178 UTC [1339] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1089niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.43ms)1090niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (5.56ms)1091niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001092niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (5.65ms)1093niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001094niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (5.61ms)1095niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001096niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (6.33ms)1097niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.75ms)1098niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (13.12ms)1099niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (6.58ms)1100niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (6.42ms)1101niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001102niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.81ms)1103niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001104niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.29ms)1105niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.4ms)1106niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.45ms)1107niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (13.02ms)1108niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (17.04ms)1109niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (14.08ms)1110niks3-go-unit-tests> 2026-06-29 09:14:15.184 UTC [1340] ERROR:  relation "goose_db_version" does not exist at character 361111niks3-go-unit-tests> 2026-06-29 09:14:15.184 UTC [1340] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1112niks3-go-unit-tests> 2026-06-29 09:14:15.187 UTC [1341] ERROR:  relation "goose_db_version" does not exist at character 361113niks3-go-unit-tests> 2026-06-29 09:14:15.187 UTC [1341] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1114niks3-go-unit-tests> 2026-06-29 09:14:15.187 UTC [1344] ERROR:  relation "goose_db_version" does not exist at character 361115niks3-go-unit-tests> 2026-06-29 09:14:15.187 UTC [1344] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1116niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (5.8ms)1117niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (4.51ms)1118niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1342] ERROR:  relation "goose_db_version" does not exist at character 361119niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1342] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1120niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21121niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (4.63ms)1122niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1343] ERROR:  relation "goose_db_version" does not exist at character 361123niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1343] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1124niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21125niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (4.59ms)1126niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (4.63ms)1127niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1345] ERROR:  relation "goose_db_version" does not exist at character 361128niks3-go-unit-tests> 2026-06-29 09:14:15.188 UTC [1345] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1129niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (6.65ms)1130niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (17.3ms)1131niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (7.29ms)1132niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001133niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (9.25ms)1134niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001135niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (6.82ms)1136niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (6.26ms)1137niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21138niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (20.84ms)1139niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (7.66ms)1140niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001141niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (13.7ms)1142niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001143niks3-go-unit-tests> 2026-06-29 09:14:15.194 UTC [1362] ERROR:  relation "goose_db_version" does not exist at character 361144niks3-go-unit-tests> 2026-06-29 09:14:15.194 UTC [1362] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1145niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (14.47ms)1146niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (12.93ms)1147niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (14.89ms)1148niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (15.3ms)1149niks3-go-unit-tests> 2026-06-29 09:14:15.197 UTC [1363] ERROR:  relation "goose_db_version" does not exist at character 361150niks3-go-unit-tests> 2026-06-29 09:14:15.197 UTC [1363] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1151niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1152niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug723806553/001/store/00kjzfc03w07dc8n4i3gvri76a82900h-file1.txt1153niks3-go-unit-tests> 2026-06-29 09:14:15.197 UTC [1364] ERROR:  relation "goose_db_version" does not exist at character 361154niks3-go-unit-tests> 2026-06-29 09:14:15.197 UTC [1364] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1155niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.52ms)1156niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (2.52ms)1157niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21158niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.96ms)1159niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.53ms)1160niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21161niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.49ms)1162niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21163niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket121164niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket131165niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3.68ms)1166niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (4.71ms)1167niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.79ms)1168niks3-go-unit-tests> 2026-06-29 09:14:15.201 UTC [1365] ERROR:  relation "goose_db_version" does not exist at character 361169niks3-go-unit-tests> 2026-06-29 09:14:15.201 UTC [1365] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1170niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.56ms)1171niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (4.88ms)1172niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.66ms)1173niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.05ms)1174niks3-go-unit-tests> 2026-06-29 09:14:15.203 UTC [1367] ERROR:  relation "goose_db_version" does not exist at character 361175niks3-go-unit-tests> 2026-06-29 09:14:15.203 UTC [1367] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1176niks3-go-unit-tests> 2026-06-29 09:14:15.203 UTC [1366] ERROR:  relation "goose_db_version" does not exist at character 361177niks3-go-unit-tests> 2026-06-29 09:14:15.203 UTC [1366] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1178niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.65s)1179niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (16.39ms)1180niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21181niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (17.77ms)1182niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (16.5ms)1183niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21184niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (16.75ms)1185niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (17.11ms)1186niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (17.64ms)1187niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (16.22ms)1188niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (16.81ms)1189niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (19.47ms)1190niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (15.21ms)1191niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (18.03ms)1192niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (16.02ms)1193niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001194niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001195niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (17.45ms)1196niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.9ms)1197niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21198niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (20.72ms)1199niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (21.95ms)1200niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (21.96ms)1201niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (24.87ms)1202niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (21.97ms)1203niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (17.71ms)1204niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (17.4ms)1205niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001206niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (16.63ms)1207niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1208niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (19.06ms)1209niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.66s)1210niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.03ms)1211niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.11ms)1212niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1213niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.97ms)1214niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001215niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.98ms)1216niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.95ms)1217niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.34ms)1218niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.39ms)1219niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.66ms)1220niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.71ms)1221niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001222niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (4.67ms)1223niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.76ms)1224niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.66ms)1225niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.35ms)1226niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001227niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.33ms)1228niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.81ms)1229niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.35ms)1230niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (3.31ms)1231niks3-go-unit-tests> 2026/06/29 09:14:15 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1232niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.67s)1233niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.82ms)1234niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.77ms)1235niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21236niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.78ms)1237niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21238niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3.84ms)1239niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3.83ms)1240niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.93ms)1241niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (2.14ms)1242niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21243niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.01ms)1244niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1245niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (7.12ms)1246niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (4.01ms)1247niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.72ms)1248niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001249niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.75ms)1250niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001251niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (2.39ms)1252niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21253niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.22ms)1254niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.67s)1255niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.58ms)1256niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (5.69ms)1257niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001258niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.67ms)1259niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.66ms)1260niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.59ms)1261niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (2.6ms)1262niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21263niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (5.66ms)1264niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (9.55ms)1265niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=01266niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (2.7ms)1267niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21268niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (5.67ms)1269niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001270niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (6.74ms)1271niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.66ms)1272niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001273niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (10.48ms)1274niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1275niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1276niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.36ms)1277niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (3.45ms)1278niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.33ms)1279niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (11.29ms)1280niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)1281niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001282niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (11.26ms)1283niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.43ms)1284niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20241026095416_initial_model.sql (10.84ms)1285niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1286niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1287niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1288niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1289niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1290niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1291niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1292niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1293niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1294niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1295niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.84ms)1296niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21297niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1298niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.65ms)1299niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.87ms)1300niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21301niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.77ms)1302niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (4.76ms)1303niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001304niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001305niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.77ms)1306niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001307niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1308niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.75ms)1309niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001310niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.46ms)1311niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.66ms)1312niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001313niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.51ms)1314niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.71ms)1315niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21316niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.61ms)1317niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001318niks3-go-unit-tests> 2026/06/29 09:14:15 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]1319niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (1.8ms)1320niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.25ms)1321niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.6ms)1322niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001323niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.68s)1324niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1ms)1325niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21326niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.66ms)1327niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.3ms)1328niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21329niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket251330niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (2.82ms)1331niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251210153512_drop_unused_gin_index.sql (2.45ms)1332niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.9ms)1333niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.87ms)1334niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.92ms)1335niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.26ms)1336niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.47ms)1337niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1338niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1339niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.38ms)1340niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.62ms)1341niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21342niks3-go-unit-tests> 2026/06/29 09:14:15 INFO OIDC auth successful provider=test1343niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Authentication failed token_preview=eyJhbGciOi...un97s0TyWQ 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]1344niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.15ms)1345niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21346niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.67s)1347niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.01ms)1348niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21349niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.38ms)1350niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21351niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.43ms)1352niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21353niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3.3ms)1354niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.67s)1355niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1356niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1357niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1358niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1359niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.9ms)1360niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3ms)1361niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (3.36ms)1362niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.54ms)1363niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.33ms)1364niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21365niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21366niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.68s)1367niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1368niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20251218171726_add_pins.sql (2.63ms)1369niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.28ms)1370niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21371niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=01372niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.98ms)1373niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001374niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.37ms)1375niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001376niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.1ms)1377niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001378niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.23ms)1379niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001380niks3-go-unit-tests> 2026/06/29 09:14:15 OK   20260628120000_add_object_size_and_stats.sql (3.19ms)1381niks3-go-unit-tests> 2026/06/29 09:14:15 goose: successfully migrated database to version: 202606281200001382niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (2.26ms)1383niks3-go-unit-tests> === NAME  TestClientIntegration1384niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration2544797456/002/store/hf8a0161x0h2pxsjrmqk3yx71d2lq3cn-test-file.txt1385niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.57ms)1386niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.42ms)1387niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Force mode enabled - objects will be deleted immediately without grace period1388niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1389niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1390niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: bound subjects configured but subject DN unavailable1391niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1392niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.68s)1393niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.71ms)1394niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1395niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.68s)1396niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (958.9µs)1397niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21398niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1399niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=11400niks3-go-unit-tests> 2026/06/29 09:14:15 OK   1_commit_pending_closure.sql (1.5ms)1401niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.12ms)1402niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21403niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.12ms)1404niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21405niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (1.13ms)1406niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21407niks3-go-unit-tests> 2026/06/29 09:14:15 OK   2_object_stats_trigger.sql (943.44µs)1408niks3-go-unit-tests> 2026/06/29 09:14:15 goose: up to current file version: 21409niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1410niks3-go-unit-tests> 2026-06-29 09:14:15.244 UTC [1328] ERROR:  Closure does not exist: id=11411niks3-go-unit-tests> 2026-06-29 09:14:15.244 UTC [1328] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1412niks3-go-unit-tests> 2026-06-29 09:14:15.244 UTC [1328] STATEMENT:  -- name: CommitPendingClosure :exec1413niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1414niks3-go-unit-tests> 	1415niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.68s)1416niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1417niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1418niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1419niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1420niks3-go-unit-tests> 2026/06/29 09:14:15 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1421niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.68s)1422niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.69s)1423niks3-go-unit-tests> 2026/06/29 09:14:15 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=01424niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.69s)1425niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket421426niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.69s)1427niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_closures1428niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_objects1429niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created nix-cache-info in bucket bucket=bucket441430niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=multipart_uploads1431niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=closures1432niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=objects1433niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.70s)1434niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1435niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.70s)1436niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=11437niks3-go-unit-tests> --- PASS: TestGCMetrics (0.71s)1438niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.71s)1439niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.71s)1440niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.71s)1441niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1442niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC3329874214/001/store/95jjpkj5j810jcp50q1i3lvvgiwwbhqc-pinned-file.txt1443niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC3329874214/001/store/chnja0z8x0dq276hq459r9mcngi3v59w-unpinned-file.txt1444niks3-go-unit-tests> === NAME  TestClientMultipleUploads1445niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads4069742848/001/store/42yw77ipqalypvainac47zyahv9zjkk5-test-file-0.txt1446niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1447niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1448niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 00kjzfc03w07dc8n4i3gvri76a82900h-file1.txt (160B)1449niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads4069742848/001/store/xpkfb7xs8ccmw5jyc0pj18g5bxai5y4s-test-file-1.txt1450niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1451niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11452niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1453niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1454niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11455niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (85ms)1456niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1457niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1458niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug723806553/001/store/00kjzfc03w07dc8n4i3gvri76a82900h-file1.txt1459niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1460niks3-go-unit-tests>         Compression: zstd1461niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1462niks3-go-unit-tests>         NarSize: 1601463niks3-go-unit-tests>         References: 1464niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1465niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1466niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1467niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1468niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1469niks3-go-unit-tests> === NAME  TestClientCADerivations1470niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2139714796/001/store/vib7d6jdl42vprwf7c5rh4sq18ajzhcq-ca-test1471niks3-go-unit-tests> === NAME  TestClientWithDependencies1472niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies1077901652/001/store/zgdgqg3mkrpdgrgk60701dqfk9fm87yz-test-script1473niks3-go-unit-tests> === NAME  TestClientMultipleUploads1474niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads4069742848/001/store/rczmhhhw9s8gbj2vmp6xjvnia4513dkb-test-file-2.txt1475niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1476niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1477niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug723806553/001/store/9955dkmfpsscqc01nbdlg6nqsjrrq0rv-file2.txt1478niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1479niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading hf8a0161x0h2pxsjrmqk3yx71d2lq3cn-test-file.txt (152B)1480niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1481niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11482niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1483niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1484niks3-go-unit-tests> === NAME  TestClientWithDependencies1485niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1486niks3-go-unit-tests> === NAME  TestClientCADerivations1487niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1488niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1489niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11490niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (91ms)1491niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1492niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 95jjpkj5j810jcp50q1i3lvvgiwwbhqc-pinned-file.txt (128B)1493niks3-go-unit-tests> === NAME  TestClientIntegration1494niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1495niks3-go-unit-tests>         StorePath: /build/TestClientIntegration2544797456/002/store/hf8a0161x0h2pxsjrmqk3yx71d2lq3cn-test-file.txt1496niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1497niks3-go-unit-tests>         Compression: zstd1498niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11499niks3-go-unit-tests>         NarSize: 1521500niks3-go-unit-tests>         References: 1501niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11502niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1503niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1504niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1505niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1506niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1507niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11508niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1509niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1510niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1511niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11512niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (84ms)1513niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=865.804563ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1514niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1515niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Garbage collection started1516niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=01517niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Force mode enabled - objects will be deleted immediately without grace period1518niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1519niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1520niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1521niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1522niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading rczmhhhw9s8gbj2vmp6xjvnia4513dkb-test-file-2.txt (160B)1523niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 42yw77ipqalypvainac47zyahv9zjkk5-test-file-0.txt (160B)1524niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading xpkfb7xs8ccmw5jyc0pj18g5bxai5y4s-test-file-1.txt (160B)1525niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.88s)1526niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1527niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1528niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.17s)1529niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.07s)1530niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.08s)1531niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.72s)1532niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1533niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=2 count=11534niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1535niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1536niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=2 count=11537niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1538niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=3 count=11539niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1540niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11541niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 3 narinfos1542niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1543niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=21544niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (70ms)1545niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1546niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1547niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug723806553/001/store/9955dkmfpsscqc01nbdlg6nqsjrrq0rv-file2.txt1548niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1549niks3-go-unit-tests>         Compression: zstd1550niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1551niks3-go-unit-tests>         NarSize: 1601552niks3-go-unit-tests>         References: 1553niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1554niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1555niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1556niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1557niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1558niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1559niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11560niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1561niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1562niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading zgdgqg3mkrpdgrgk60701dqfk9fm87yz-test-script (136B)1563niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=21564niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1565niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.90s)1566niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=31567niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (89ms)1568niks3-go-unit-tests> === NAME  TestClientMultipleUploads1569niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 122.433563ms1570niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1571niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11572niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1573niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1574niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11575niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (48ms)1576niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.91s)1577niks3-go-unit-tests> === NAME  TestClientWithDependencies1578niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies1077901652/001/store) requires matching store prefix1579niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.91s)1580niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1581niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1582niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1583niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1584niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1585niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1586niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1587niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.92s)1588niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1589niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1590niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1591niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading chnja0z8x0dq276hq459r9mcngi3v59w-unpinned-file.txt (128B)1592niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1593niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading vib7d6jdl42vprwf7c5rh4sq18ajzhcq-ca-test (144B)1594niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1595niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=2 count=11596niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1597niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1598niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Signed narinfos id=1 count=11599niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Uploading 1 narinfos1600niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1601niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=21602niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (78ms)1603niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1604niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11605niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Upload complete. (81ms)1606niks3-go-unit-tests> === NAME  TestClientCADerivations1607niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2139714796/001/store/vib7d6jdl42vprwf7c5rh4sq18ajzhcq-ca-test1608niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1609niks3-go-unit-tests>         Compression: zstd1610niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1611niks3-go-unit-tests>         NarSize: 1441612niks3-go-unit-tests>         References: 1613niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations2139714796/001/store/5i9v3c87cw19nzp8l82k9r57jcakr25q-ca-test.drv1614niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1615niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1616niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1617niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1618niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1619niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1620niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NWQ4YTdlOWItNzUwNC00MDFhLWIwZWEtNjc2ZDc1MDM4MmIzLjdhN2RjMmJlLTVhZjYtNDVmNy04YTgzLTM3Nzc2YWIyYTlhZngxNzgyNzI0NDU1MjQ2MDQyODUz parts=101621niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1622niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NWQ4YTdlOWItNzUwNC00MDFhLWIwZWEtNjc2ZDc1MDM4MmIzLmZiYjJmZjI5LTg4NjItNDczYy1hNWU3LTE5NTFmMzNjMzgwYngxNzgyNzI0NDU1MjU4ODEzODk0 parts=101623niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1624niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received create pin request method=POST path=/api/pins/myapp1625niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11626niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed upload id=11627niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC3329874214/001/store/95jjpkj5j810jcp50q1i3lvvgiwwbhqc-pinned-file.txt narinfo_key=95jjpkj5j810jcp50q1i3lvvgiwwbhqc.narinfo1628niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001629niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1630niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1631niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1632niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Garbage collection started1633niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received uploads request method=POST path=/api/pending_closures1634niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1635niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1636niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Found objects in DB but missing from S3, will re-upload count=11637niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.98s)1638niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1639niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=01640niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Aborted multipart uploads count=01641niks3-go-unit-tests> 2026/06/29 09:14:15 WARN Force mode enabled - objects will be deleted immediately without grace period1642niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=NWQ4YTdlOWItNzUwNC00MDFhLWIwZWEtNjc2ZDc1MDM4MmIzLmMxYmY5ZmQzLWM3NjEtNDAwNC1hMjQ5LWM2YjRlNDg2ODFiN3gxNzgyNzI0NDU1MjI3NDI2NzE2 parts=121643niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.98s)1644niks3-go-unit-tests> 2026/06/29 09:14:15 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=01645niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_closures1646niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_objects1647niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=multipart_uploads1648niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=closures1649niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=objects1650niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1651niks3-go-unit-tests> {"timestamp":"2026-06-29T09:14:15.578886958Z","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(756)"}1652niks3-go-unit-tests> {"timestamp":"2026-06-29T09:14:15.578921478Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket28, 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(756)"}1653niks3-go-unit-tests> 2026/06/29 09:14:15 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=NWQ4YTdlOWItNzUwNC00MDFhLWIwZWEtNjc2ZDc1MDM4MmIzLmU3Mzk2MDIwLTZkODMtNDI5Mi1iYzlkLThmMGVjZDYwZDQ1NngxNzgyNzI0NDU1MjQ0NTU5ODY01654niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NWQ4YTdlOWItNzUwNC00MDFhLWIwZWEtNjc2ZDc1MDM4MmIzLmU3Mzk2MDIwLTZkODMtNDI5Mi1iYzlkLThmMGVjZDYwZDQ1NngxNzgyNzI0NDU1MjQ0NTU5ODY0 parts=11655niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (1.02s)1656niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001657niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (1.03s)1658niks3-go-unit-tests> === NAME  TestClientCADerivations1659niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1660niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1661niks3-go-unit-tests>         error: binary cache 's3://bucket44?endpoint=http://localhost:42165&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2139714796/001/store'1662niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11663niks3-go-unit-tests> --- PASS: TestClientCADerivations (1.07s)1664niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1665niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1666niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1667niks3-go-unit-tests> 2026/06/29 09:14:15 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=01668niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_closures1669niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_objects1670niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=multipart_uploads1671niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=closures1672niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=objects1673niks3-go-unit-tests> 2026/06/29 09:14:15 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=01674niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_closures1675niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=pending_objects1676niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=multipart_uploads1677niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=closures1678niks3-go-unit-tests> 2026/06/29 09:14:15 INFO Vacuumed table table=objects1679niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1680niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201681niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101682niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101683niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.35s)1684niks3-go-unit-tests> 2026/06/29 09:14:16 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.754684566s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1685niks3-go-unit-tests> 2026/06/29 09:14:17 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01686niks3-go-unit-tests> === NAME  TestClientIntegration1687niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1688niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1689niks3-go-unit-tests> --- PASS: TestClientIntegration (2.86s)1690niks3-go-unit-tests> 2026/06/29 09:14:17 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  TestPinProtectsFromGC1692niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1693niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.99s)1694niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1695niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.71s)1696niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.82s)1697niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.46s)1698niks3-go-unit-tests> 2026/06/29 09:14:18 WARN Rate limiter enabled after throttle name=s3-test rate=51699niks3-go-unit-tests> 2026/06/29 09:14:18 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1700niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1701niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101702niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001703niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.36s)1704niks3-go-unit-tests> PASS1705niks3-go-unit-tests> {"timestamp":"2026-06-29T09:14:19.429212883Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:46634"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(493)"}1706niks3-go-unit-tests> 2026-06-29 09:14:19.552 UTC [293] LOG:  received smart shutdown request1707niks3-go-unit-tests> 2026-06-29 09:14:19.555 UTC [293] LOG:  background worker "logical replication launcher" (PID 300) exited with exit code 11708niks3-go-unit-tests> 2026-06-29 09:14:19.560 UTC [295] LOG:  shutting down1709niks3-go-unit-tests> 2026-06-29 09:14:19.561 UTC [295] LOG:  checkpoint starting: shutdown immediate1710niks3-go-unit-tests> 2026-06-29 09:14:20.660 UTC [295] LOG:  checkpoint complete: wrote 8512 buffers (52.0%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.137 s, sync=0.955 s, total=1.100 s; sync files=14421, longest=0.010 s, average=0.001 s; distance=194059 kB, estimate=194059 kB; lsn=0/D26F7D8, redo lsn=0/D26F7D81711niks3-go-unit-tests> 2026-06-29 09:14:20.724 UTC [293] LOG:  database system is shut down1712niks3-go-unit-tests> Running OIDC tests...1713niks3-go-unit-tests> === RUN   TestGlobMatch1714niks3-go-unit-tests> === PAUSE TestGlobMatch1715niks3-go-unit-tests> === RUN   TestAudienceForIssuer1716niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1717niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1718niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1719niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1720niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1721niks3-go-unit-tests> === RUN   TestValidateToken_Expired1722niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1723niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1724niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1725niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1726niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1727niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1728niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1729niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1730niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1731niks3-go-unit-tests> === CONT  TestGlobMatch1732niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1733niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1734niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1735niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1736niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1737niks3-go-unit-tests> === CONT  TestAudienceForIssuer1738niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1739niks3-go-unit-tests> === CONT  TestValidateToken_Expired1740niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1741niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1742niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1743niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1744niks3-go-unit-tests> === RUN   TestGlobMatch/*_1745niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1746niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1747niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1748niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1749niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1750niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1751niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1752niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1753niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1754niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1755niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1756niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1757niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1758niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1759niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1760niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1761niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1762niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1763niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1764niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1765niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1766niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1767niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1768niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1769niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1770niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1771niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1772niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1773niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01774niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01775niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1776niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1777niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1778niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1779niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1780niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1781niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1782niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1783niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1784niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1785niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1786niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1787niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1789niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1790niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1791niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1792niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1793niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1794niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1795niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1796niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1797niks3-go-unit-tests> === CONT  TestGlobMatch/*_1798niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1799niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1800niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1801niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1802niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1803niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1804niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1805niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1806niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01807niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1808niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1809niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1810niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1811niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1812niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1813niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1814niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1815niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1816niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1842niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=test1843niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=provider11844niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=test1845niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=test1846niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=test1847niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=test1848niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=provider11849niks3-go-unit-tests> 2026/06/29 09:14:21 INFO OIDC provider initialized name=provider21850niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1851niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1852niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1853niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1854niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1855niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1856niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1857niks3-go-unit-tests> PASS1858niks3-go-unit-tests> Running hook tests...1859niks3-go-unit-tests> === RUN   TestSendPathsEmpty1860niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1861niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1862niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1863niks3-go-unit-tests> === RUN   TestQueueDeduplication1864niks3-go-unit-tests> === PAUSE TestQueueDeduplication1865niks3-go-unit-tests> === RUN   TestQueueRemove1866niks3-go-unit-tests> === PAUSE TestQueueRemove1867niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1868niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1869niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1870niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1871niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1872niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1873niks3-go-unit-tests> === RUN   TestServerClientIntegration1874niks3-go-unit-tests> === PAUSE TestServerClientIntegration1875niks3-go-unit-tests> === RUN   TestServerQueueError1876niks3-go-unit-tests> === PAUSE TestServerQueueError1877niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1878niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1879niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1880niks3-go-unit-tests>         PASS1881niks3-go-unit-tests>         1882niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.03s)1883niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1884niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1885niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1886niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1887niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1888niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1889niks3-go-unit-tests> === CONT  TestSendPathsEmpty1890niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1891niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1892niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1893niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1894niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1895niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1896niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1897niks3-go-unit-tests> === CONT  TestServerQueueError1898niks3-go-unit-tests> === CONT  TestQueueDeduplication1899niks3-go-unit-tests> === CONT  TestQueueRemove1900niks3-go-unit-tests> === CONT  TestServerClientIntegration1901niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1902niks3-go-unit-tests> 2026/06/29 09:14:21 ERROR Failed to queue paths error="permission denied" count=11903niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1904niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1905niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Upload queue status pending=21906niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Uploading batch count=11907niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1908niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1909niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1910niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Upload queue status pending=21911niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Uploading batch count=21912niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Upload queue status pending=21913niks3-go-unit-tests> 2026/06/29 09:14:21 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2603907397/002/nonexistent1914niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1915niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1916niks3-go-unit-tests> 2026/06/29 09:14:21 INFO Uploading batch count=11917niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.07s)1918niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.07s)1919niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1920niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.22s)1921niks3-go-unit-tests> PASS19221923post-build step Upload coverage to codecov: ok1924Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests