nixbot

1this derivation will be built:2  /nix/store/1d8j1qn9f0c3lgjz02db7bl6lrq82kz8-niks3-go-unit-tests.drv3building '/nix/store/1d8j1qn9f0c3lgjz02db7bl6lrq82kz8-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/1d8j1qn9f0c3lgjz02db7bl6lrq82kz8-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  TestPathInfoHashCompatibility76niks3-go-unit-tests> === CONT  TestSetClientTLS77niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)78niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)79niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon80niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall81niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon82niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI83niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI84niks3-go-unit-tests> === CONT  TestShellSplitErrors85niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)86niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility87niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field88niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha51289niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha51290niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths91niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths92niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody93niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths94niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths95niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths96niks3-go-unit-tests> === CONT  TestParsePathInfoJSON97niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format98niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format99niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format100niks3-go-unit-tests> === CONT  TestResolveStorePath101niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess102niks3-go-unit-tests> === CONT  TestRateLimiterFeedback103niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter104niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter105niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter106niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter107niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter108niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter109niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter110niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter111niks3-go-unit-tests> === CONT  TestShellSplit112niks3-go-unit-tests> === CONT  TestFileTokenEmpty113niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)114niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON115niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter enabled after throttle name=server-test rate=5116niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field117niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text118niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text119niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format120niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input121niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive122niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input123niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only124niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive125niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only126niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text127niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text128niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method129niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method130niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON131niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON132niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand133niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)134niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken135niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails136niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)137niks3-go-unit-tests> === CONT  TestStaticToken138niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)139niks3-go-unit-tests> === CONT  TestDumpPathWriterError140niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)141niks3-go-unit-tests> === CONT  TestSetClientTLSErrors142niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter enabled after throttle name=server-test rate=5143niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52424144niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)145niks3-go-unit-tests> === CONT  TestGetStorePathHash146niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path147niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path148niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error149niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error150niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter backed off name=server-test rate=5151niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error152niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error153niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52424154niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error155niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error156niks3-go-unit-tests> === CONT  TestConvertHashToNix32157niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32158niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32159niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format160niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format161niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format162niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format163niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash164niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)165niks3-go-unit-tests> === CONT  TestEncodeNixBase32166niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash167niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash168niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input169niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input170niks3-go-unit-tests> === CONT  TestFileTokenMissing171niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)172niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh173niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file174niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file175niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file176niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file177niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file178niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file179niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file180niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file181niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer182niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists183niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists184niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing185niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing186niks3-go-unit-tests> === CONT  TestDumpPathSingleFile187niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert188niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert189niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA190niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA191niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport192niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport193niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)194niks3-go-unit-tests> === CONT  TestPartSizeForNAR195niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum196niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum197niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum198niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum199niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix200niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum201niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum202niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts203niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts204niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB205niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB206niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object207niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object208niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB209niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB210niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport211niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)212niks3-go-unit-tests> === CONT  TestCaseHackSuffix213niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)214niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches215niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)216niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)217niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths218niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon219niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI220niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512221niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)222niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)223niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)224niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)225niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)226niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths227niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)228niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)229niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)230niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter231niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter enabled after throttle name=server-test rate=5232niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:52430233niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter backed off name=server-test rate=5234niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter235niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter236niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.02s)237niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter238niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter enabled after throttle name=server-test rate=5239niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:52434240niks3-go-unit-tests> 2026/06/28 14:38:02 WARN Rate limiter backed off name=server-test rate=5241niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field242niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)243niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)244niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)245niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)246niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)247niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text248niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive249niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON250niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format251niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text252niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only253niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input254niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method255niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format256niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)257niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)258niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)259niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)260niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)261niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)262niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path263niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error264niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)265niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)266niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)267niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)268niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)269niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)270niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error271niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error272niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32273niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)274niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)275niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)276niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)277niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)278niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format279niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format280niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)281niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)282niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)283niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)284niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash285niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input286niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)287niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)288niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)289niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file290niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists291niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file292niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)293niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file294niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file295niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing296niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert297niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport298niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)299niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)300niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)301niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)302niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)303niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)304niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)305niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)306niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA307niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum308niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB309niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB310niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object311niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum312niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts313niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum314niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)315niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)316niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)317niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)318niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)319niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)320niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)321niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)322niks3-go-unit-tests> 2026/06/28 14:38:02 http: TLS handshake error from 127.0.0.1:52441: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)328niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.03s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.05s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.06s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.06s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.07s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-67679-129173745/postgres3166586052/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /nix/var/nix/builds/nix-67679-129173745/postgres3166586052/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-67679-129173745/postgres3166586052:5432 - no response364niks3-go-unit-tests> 2026-06-28 14:38:03.802 UTC [67714] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 14:38:03.802 UTC [67714] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-67679-129173745/postgres3166586052/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 14:38:03.804 UTC [67718] LOG:  database system was shut down at 2026-06-28 14:38:03 UTC367niks3-go-unit-tests> 2026-06-28 14:38:03.805 UTC [67714] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-67679-129173745/postgres3166586052:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:03.930322Z","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(5)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestCacheStatsHandler384niks3-go-unit-tests> === PAUSE TestCacheStatsHandler385niks3-go-unit-tests> === RUN   TestClientCADerivations386niks3-go-unit-tests> === PAUSE TestClientCADerivations387niks3-go-unit-tests> === RUN   TestClientErrorHandling388niks3-go-unit-tests> === PAUSE TestClientErrorHandling389niks3-go-unit-tests> === RUN   TestClientIntegration390niks3-go-unit-tests> === PAUSE TestClientIntegration391niks3-go-unit-tests> === RUN   TestClientMultipleUploads392niks3-go-unit-tests> === PAUSE TestClientMultipleUploads393niks3-go-unit-tests> === RUN   TestClientWithDependencies394niks3-go-unit-tests> === PAUSE TestClientWithDependencies395niks3-go-unit-tests> === RUN   TestPinProtectsFromGC396niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC397niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun398niks3-go-unit-tests> 2026-06-28 14:38:04.073 UTC [67769] ERROR:  relation "goose_db_version" does not exist at character 36399niks3-go-unit-tests> 2026-06-28 14:38:04.073 UTC [67769] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC400niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (3.41ms)401niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (358.71µs)402niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (685.25µs)403niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (809.67µs)404niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000405niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (840.58µs)406niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (198.96µs)407niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2408niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.07s)409niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences410niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences411niks3-go-unit-tests> === RUN   TestGCMetrics412niks3-go-unit-tests> === PAUSE TestGCMetrics413niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew414niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew415niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams416niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams417niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams418niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams419niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty420niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty421niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest422niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest423niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask424niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask425niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates426niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates427niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail428niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail429niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight430niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight431niks3-go-unit-tests> === RUN   TestService_healthCheckHandler432niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler433niks3-go-unit-tests> === RUN   TestGenerateLandingPage434niks3-go-unit-tests> === PAUSE TestGenerateLandingPage435niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug436niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug437niks3-go-unit-tests> === RUN   TestMetricsInventory438niks3-go-unit-tests> === PAUSE TestMetricsInventory439niks3-go-unit-tests> === RUN   TestService_NativeMTLS440niks3-go-unit-tests> === PAUSE TestService_NativeMTLS441niks3-go-unit-tests> === RUN   TestServerTLSConfig442niks3-go-unit-tests> === PAUSE TestServerTLSConfig443niks3-go-unit-tests> === RUN   TestMultipartCleanup444niks3-go-unit-tests> === PAUSE TestMultipartCleanup445niks3-go-unit-tests> === RUN   TestObjectStatsTrigger446niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger447niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC448niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC449niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest450niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest451niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted452niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted453niks3-go-unit-tests> === RUN   TestParseSingleRange454niks3-go-unit-tests> === PAUSE TestParseSingleRange455niks3-go-unit-tests> === RUN   TestIsValidCachePath456niks3-go-unit-tests> === PAUSE TestIsValidCachePath457niks3-go-unit-tests> === RUN   TestReadProxyNarinfo458niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo459niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed460niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed461niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming462niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming463niks3-go-unit-tests> === RUN   TestReadProxy404464niks3-go-unit-tests> === PAUSE TestReadProxy404465niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath466niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath467niks3-go-unit-tests> === RUN   TestReadProxyHead468niks3-go-unit-tests> === PAUSE TestReadProxyHead469niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet470niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet471niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML472niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML473niks3-go-unit-tests> === RUN   TestReadProxyDisabled474niks3-go-unit-tests> === PAUSE TestReadProxyDisabled475niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest476niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest477niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload478niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload479niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists480niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists481niks3-go-unit-tests> === RUN   TestService_Rustfstest482niks3-go-unit-tests> === PAUSE TestService_Rustfstest483niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated484niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)485niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy486niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)487niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy488niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"489niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"490niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"491niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/28 14:38:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)499niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle500niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle501niks3-go-unit-tests> === RUN   TestProxyWriteTimeout502niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout503niks3-go-unit-tests> === RUN   TestIsValidUploadKey504niks3-go-unit-tests> === PAUSE TestIsValidUploadKey505niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys506niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys507niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody508niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody509niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler510niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler511niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler512niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler513niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity514niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity515niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered516niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered517niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT518niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT519niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest520niks3-go-unit-tests> === CONT  TestService_AuthMiddleware521niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight522niks3-go-unit-tests> === CONT  TestParseSingleRange523niks3-go-unit-tests> === RUN   TestParseSingleRange/none524niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath525niks3-go-unit-tests> === PAUSE TestParseSingleRange/none526niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit527niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit528niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored529niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored530niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash531niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash532niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty533niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty534niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start535niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start536niks3-go-unit-tests> === RUN   TestParseSingleRange/closed537niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed538niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended539niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended540niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size541niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size542niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix543niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail544niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix545niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)546niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming547niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask548niks3-go-unit-tests> === CONT  TestReadProxyDisabled549niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)550niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest551niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)552niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty553niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)554niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams555niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)556niks3-go-unit-tests> === CONT  TestServerTLSConfig557niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA558niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA559niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file560niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size561niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file562niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size563niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte564niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte565niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF566niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF567niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF568niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF569niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file570niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted571niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file572niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates573niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)574niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest575niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC576niks3-go-unit-tests> === CONT  TestReadProxy404577niks3-go-unit-tests> 2026/06/28 14:38:04 INFO Starting HTTP server address=127.0.0.1:52455578niks3-go-unit-tests> 2026/06/28 14:38:04 INFO Shutdown signal received, draining in-flight requests timeout=10s579niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.08s)580niks3-go-unit-tests> === CONT  TestObjectStatsTrigger581niks3-go-unit-tests> 2026-06-28 14:38:04.615 UTC [67791] ERROR:  relation "goose_db_version" does not exist at character 36582niks3-go-unit-tests> 2026-06-28 14:38:04.615 UTC [67791] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC583niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67792] ERROR:  relation "goose_db_version" does not exist at character 36584niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67792] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC585niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67794] ERROR:  relation "goose_db_version" does not exist at character 36586niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67794] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC587niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67795] ERROR:  relation "goose_db_version" does not exist at character 36588niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67795] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC589niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67793] ERROR:  relation "goose_db_version" does not exist at character 36590niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67793] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC591niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67798] ERROR:  relation "goose_db_version" does not exist at character 36592niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67798] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC593niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67797] ERROR:  relation "goose_db_version" does not exist at character 36594niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67797] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC595niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67796] ERROR:  relation "goose_db_version" does not exist at character 36596niks3-go-unit-tests> 2026-06-28 14:38:04.624 UTC [67796] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC597niks3-go-unit-tests> 2026-06-28 14:38:04.625 UTC [67799] ERROR:  relation "goose_db_version" does not exist at character 36598niks3-go-unit-tests> 2026-06-28 14:38:04.625 UTC [67799] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC599niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (5.82ms)600niks3-go-unit-tests> 2026-06-28 14:38:04.629 UTC [67800] ERROR:  relation "goose_db_version" does not exist at character 36601niks3-go-unit-tests> 2026-06-28 14:38:04.629 UTC [67800] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC602niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (684.5µs)603niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.58ms)604niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (2.11ms)605niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000606niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (6.46ms)607niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (7.19ms)608niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (785.63µs)609niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (7.85ms)610niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (783.71µs)611niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (2.36ms)612niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (8.6ms)613niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (8.07ms)614niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (1.02ms)615niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (7.37ms)616niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (7.19ms)617niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (6.84ms)618niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (473.71µs)619niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2620niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.55ms)621niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.93ms)622niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (576.38µs)623niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (580.71µs)624niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (900.17µs)625niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (693.25µs)626niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (789.25µs)627niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.24ms)628niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.18ms)629niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000630niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.24ms)631niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000632niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.1ms)633niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.62ms)634niks3-go-unit-tests> 2026/06/28 14:38:04 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"635niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.33s)636niks3-go-unit-tests> === CONT  TestMultipartCleanup637niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.78ms)638niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.73ms)639niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (2.19ms)640niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (2.03ms)641niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000642niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (6.72ms)643niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.75ms)644niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (2.03ms)645niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000646niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (2.17ms)647niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (2.06ms)648niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000649niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.57ms)650niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000651niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (861.21µs)652niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2653niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.79ms)654niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000655niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (905.21µs)656niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (713.29µs)657niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2658niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.47ms)659niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.77ms)660niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000661niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.52ms)662niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (574.5µs)663niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2664niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.14ms)665niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (1.2ms)666niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.72ms)667niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (680µs)668niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2669niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.83ms)670niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.5ms)671niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (592.21µs)672niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2673niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (422.75µs)674niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2675niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (418.71µs)676niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2677niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (540.17µs)678niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2679niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.33s)680niks3-go-unit-tests> === CONT  TestService_NativeMTLS681niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (1.75ms)682niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000683niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.33s)684niks3-go-unit-tests> === CONT  TestMetricsInventory685niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:04.645915Z","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(3)"}686niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (2.58ms)687niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.34s)688niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug689niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (1.38ms)690niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2691niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.34s)692niks3-go-unit-tests> === CONT  TestGenerateLandingPage693niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)694niks3-go-unit-tests> === CONT  TestService_healthCheckHandler695niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.26s)696niks3-go-unit-tests> === CONT  TestClientIntegration697niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.34s)698niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams699niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)700niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew701niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)702niks3-go-unit-tests> === CONT  TestGCMetrics703niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.35s)704niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences705niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC706niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:707niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A708niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B709niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)710niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)711niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects712niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.57s)713niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys714niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info715niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info716niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal717niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal718niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key719niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key720niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key721niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key722niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT723niks3-go-unit-tests> 2026-06-28 14:38:04.956 UTC [67840] ERROR:  relation "goose_db_version" does not exist at character 36724niks3-go-unit-tests> 2026-06-28 14:38:04.956 UTC [67840] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC725niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20241026095416_initial_model.sql (13.06ms)726niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251210153512_drop_unused_gin_index.sql (960.5µs)727niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20251218171726_add_pins.sql (2.16ms)728niks3-go-unit-tests> 2026/06/28 14:38:04 OK   20260628120000_add_object_size_and_stats.sql (3.8ms)729niks3-go-unit-tests> 2026/06/28 14:38:04 goose: successfully migrated database to version: 20260628120000730niks3-go-unit-tests> 2026/06/28 14:38:04 OK   1_commit_pending_closure.sql (1.56ms)731niks3-go-unit-tests> 2026/06/28 14:38:04 OK   2_object_stats_trigger.sql (218.17µs)732niks3-go-unit-tests> 2026/06/28 14:38:04 goose: up to current file version: 2733niks3-go-unit-tests> 2026/06/28 14:38:04 INFO Received uploads request method=POST path=/api/pending_closures734niks3-go-unit-tests> 2026-06-28 14:38:04.989 UTC [67841] ERROR:  relation "goose_db_version" does not exist at character 36735niks3-go-unit-tests> 2026-06-28 14:38:04.989 UTC [67841] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC736niks3-go-unit-tests> 2026-06-28 14:38:05.006 UTC [67842] ERROR:  relation "goose_db_version" does not exist at character 36737niks3-go-unit-tests> 2026-06-28 14:38:05.006 UTC [67842] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC738niks3-go-unit-tests> 2026-06-28 14:38:05.011 UTC [67843] ERROR:  relation "goose_db_version" does not exist at character 36739niks3-go-unit-tests> 2026-06-28 14:38:05.011 UTC [67843] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC740niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (17.42ms)741niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (422.63µs)742niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.42ms)743niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (3.42ms)744niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000745niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (964.08µs)746niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (203.88µs)747niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2748niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (7.84ms)749niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (387.71µs)750niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Created nix-cache-info in bucket bucket=bucket13751niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.16ms)752niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (2.25ms)753niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000754niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (8.04ms)755niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.43ms)756niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (350.33µs)757niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2758niks3-go-unit-tests> 2026/06/28 14:38:05 WARN mTLS auth: subject not in bound subjects subject="CN=reader"759niks3-go-unit-tests> 2026/06/28 14:38:05 WARN mTLS auth: subject not in bound subjects subject="CN=writer"760niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.39s)761niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered762niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (2.63ms)763niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.75ms)764niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (2.16ms)765niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000766niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (930.5µs)767niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (273.96µs)768niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2769niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.40s)770niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity771niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received cleanup request method=DELETE path=/api/pending_closures772niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=1773niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug774niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-67679-129173745/TestNARDeduplicationMetadataUploadBug760613405/001/store/cf39gfj6qzfwsw5sp0h1irqjlgmcwwsk-file1.txt775niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.49s)776niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler777niks3-go-unit-tests> 2026-06-28 14:38:05.138 UTC [67853] ERROR:  relation "goose_db_version" does not exist at character 36778niks3-go-unit-tests> 2026-06-28 14:38:05.138 UTC [67853] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC779niks3-go-unit-tests> 2026-06-28 14:38:05.138 UTC [67852] ERROR:  relation "goose_db_version" does not exist at character 36780niks3-go-unit-tests> 2026-06-28 14:38:05.138 UTC [67852] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC781niks3-go-unit-tests> 2026-06-28 14:38:05.142 UTC [67855] ERROR:  relation "goose_db_version" does not exist at character 36782niks3-go-unit-tests> 2026-06-28 14:38:05.142 UTC [67855] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC783niks3-go-unit-tests> 2026-06-28 14:38:05.142 UTC [67856] ERROR:  relation "goose_db_version" does not exist at character 36784niks3-go-unit-tests> 2026-06-28 14:38:05.142 UTC [67856] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC785niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest786niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains787niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (6.64ms)788niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion789niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (714.5µs)790niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (7.98ms)791niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (8.72ms)792niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (611.21µs)793niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.77ms)794niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (9.48ms)795niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (846.17µs)796niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (529.42µs)797niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.35ms)798niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (2.85ms)799niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000800niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (2.45ms)801niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (2.68ms)802niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (2.53ms)803niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000804niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.68ms)805niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (1.86ms)806niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000807niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (393.92µs)808niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2809niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.8ms)810niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (281.42µs)811niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2812niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (951.13µs)813niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (430.67µs)814niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2815niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Created nix-cache-info in bucket bucket=bucket16816niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (4.75ms)817niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000818niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (2.7ms)819niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=0820niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (633.92µs)821niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2822niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.52s)823niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler824niks3-go-unit-tests> 2026/06/28 14:38:05 WARN Force mode enabled - objects will be deleted immediately without grace period825niks3-go-unit-tests> 2026/06/28 14:38:05 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=0826niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_closures827niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_objects828niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=multipart_uploads829niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=closures830niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=objects831niks3-go-unit-tests> --- PASS: TestGCMetrics (0.52s)832niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody833niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure834niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure835niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart836niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart837niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts838niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts839niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML840niks3-go-unit-tests> === NAME  TestClientIntegration841niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-67679-129173745/TestClientIntegration3529426481/002/store/2ddw8px4fz8c2if50k1wxcygnrga11bx-test-file.txt842niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures843niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)844niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading cf39gfj6qzfwsw5sp0h1irqjlgmcwwsk-file1.txt (160B)845niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign846niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Signed narinfos id=1 count=1847niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 1 narinfos848niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete849niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed upload id=1850niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Upload complete. (121ms)851niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug852niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:853niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-67679-129173745/TestNARDeduplicationMetadataUploadBug760613405/001/store/cf39gfj6qzfwsw5sp0h1irqjlgmcwwsk-file1.txt854niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst855niks3-go-unit-tests>         Compression: zstd856niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf857niks3-go-unit-tests>         NarSize: 160858niks3-go-unit-tests>         References: 859niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf860niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)861niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):862niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}863niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest864niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:865niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 20866niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 210867niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 210868niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.01s)869niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet870niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug871niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-67679-129173745/TestNARDeduplicationMetadataUploadBug760613405/001/store/972p6d5bigc1w4kszp814q7y1cvl80k2-file2.txt872niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.72s)873niks3-go-unit-tests> 2026-06-28 14:38:05.388 UTC [67882] ERROR:  relation "goose_db_version" does not exist at character 36874niks3-go-unit-tests> 2026-06-28 14:38:05.388 UTC [67882] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC875niks3-go-unit-tests> === CONT  TestReadProxyHead876niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures877niks3-go-unit-tests> 2026-06-28 14:38:05.397 UTC [67885] ERROR:  relation "goose_db_version" does not exist at character 36878niks3-go-unit-tests> 2026-06-28 14:38:05.397 UTC [67885] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC879niks3-go-unit-tests> 2026-06-28 14:38:05.397 UTC [67886] ERROR:  relation "goose_db_version" does not exist at character 36880niks3-go-unit-tests> 2026-06-28 14:38:05.397 UTC [67886] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC881niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)882niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 2ddw8px4fz8c2if50k1wxcygnrga11bx-test-file.txt (152B)883niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign884niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Signed narinfos id=1 count=1885niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 1 narinfos886niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete887niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed upload id=1888niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (12.07ms)889niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Upload complete. (112ms)890niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (12.04ms)891niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (11.4ms)892niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (628.17µs)893niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (433µs)894niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (473.13µs)895niks3-go-unit-tests> === NAME  TestClientIntegration896niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:897niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-67679-129173745/TestClientIntegration3529426481/002/store/2ddw8px4fz8c2if50k1wxcygnrga11bx-test-file.txt898niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst899niks3-go-unit-tests>         Compression: zstd900niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1901niks3-go-unit-tests>         NarSize: 152902niks3-go-unit-tests>         References: 903niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1904niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (790.96µs)905niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (830.29µs)906niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (846µs)907niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)908niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):909niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}910niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...911niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (12.35ms)912niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000913niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (12.25ms)914niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000915niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (12.63ms)916niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 20260628120000917niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures918niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)919niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.55ms)920niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.46ms)921niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.53ms)922niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (321.5µs)923niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2924niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (313.29µs)925niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2926niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (464.79µs)927niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 2928niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign929niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Signed narinfos id=2 count=1930niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Uploading 1 narinfos931niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures932niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete multipart upload request method=POST path=/api/multipart/complete933niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete934niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures935niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed upload id=2936niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Upload complete. (76ms)937niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug938niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:939niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-67679-129173745/TestNARDeduplicationMetadataUploadBug760613405/001/store/972p6d5bigc1w4kszp814q7y1cvl80k2-file2.txt940niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst941niks3-go-unit-tests>         Compression: zstd942niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf943niks3-go-unit-tests>         NarSize: 160944niks3-go-unit-tests>         References: 945niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf946niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)947niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):948niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}949niks3-go-unit-tests> 2026/06/28 14:38:05 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst950niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.42s)951niks3-go-unit-tests> === CONT  TestClientCADerivations952niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.80s)953niks3-go-unit-tests> === CONT  TestClientErrorHandling954niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath955niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath956niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken957niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken958niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable959niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable960niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle961niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.56s)962niks3-go-unit-tests> === CONT  TestIsValidUploadKey963niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo964niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo965niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst966niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst967niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz968niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz969niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain970niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain971niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing972niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing973niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log974niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log975niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file976niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file977niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name978niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name979niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark980niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark981niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals982niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals983niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation984niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation985niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output986niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output987niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info988niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info989niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html990niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html991niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type992niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type993niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type994niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type995niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type996niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type997niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal998niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal999niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar1000niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar1001niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute1002niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute1003niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key1004niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key1005niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type1006niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type1007niks3-go-unit-tests> === CONT  TestProxyWriteTimeout1008niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo1009niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo1010niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar1011niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar1012niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar1013niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar1014niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size1015niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size1016niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed1017niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Starting cleanup of old closures method=DELETE path=/api/closures1018niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Garbage collection started1019niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=01020niks3-go-unit-tests> 2026/06/28 14:38:05 WARN Force mode enabled - objects will be deleted immediately without grace period1021niks3-go-unit-tests> 2026-06-28 14:38:05.471 UTC [67899] ERROR:  relation "goose_db_version" does not exist at character 361022niks3-go-unit-tests> 2026-06-28 14:38:05.471 UTC [67899] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1023niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (21.07ms)1024niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (518.5µs)1025niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.59ms)1026niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (5.69ms)1027niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001028niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.5ms)1029niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (322.21µs)1030niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21031niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1032niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1033niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1034niks3-go-unit-tests> 2026-06-28 14:38:05.536 UTC [67900] ERROR:  relation "goose_db_version" does not exist at character 361035niks3-go-unit-tests> 2026-06-28 14:38:05.536 UTC [67900] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1036niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1037niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=Y2JjY2MwZDUtMjNjMy00ODM2LTkxZGQtMDNmZTIzMDc5NDQwLmZlMjQ2ZTUyLTEyMmItNDZlYy1hOTJjLTE2ODA0NTc0YWViZXgxNzgyNjU3NDg1NDUyMTgyMDAw parts=101038niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1039niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed upload id=11040niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1041niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1042niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1043niks3-go-unit-tests> 2026/06/28 14:38:05 WARN Found objects in DB but missing from S3, will re-upload count=11044niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.52s)1045niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1046niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (15.52ms)1047niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (689.75µs)1048niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (2.1ms)1049niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (6.78ms)1050niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001051niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.8ms)1052niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (397.21µs)1053niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21054niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received cleanup request method=DELETE path=/api/pending_closures1055niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=01056niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1057niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received cleanup request method=DELETE path=/api/pending_closures1058niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=11059niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1060niks3-go-unit-tests> 2026-06-28 14:38:05.597 UTC [67900] ERROR:  Closure does not exist: id=11061niks3-go-unit-tests> 2026-06-28 14:38:05.597 UTC [67900] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1062niks3-go-unit-tests> 2026-06-28 14:38:05.597 UTC [67900] STATEMENT:  -- name: CommitPendingClosure :exec1063niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1064niks3-go-unit-tests> 	1065niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.42s)1066niks3-go-unit-tests> === CONT  TestPinProtectsFromGC1067niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1068niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=Y2JjY2MwZDUtMjNjMy00ODM2LTkxZGQtMDNmZTIzMDc5NDQwLjVjZmFmMjhiLWViZjAtNDBhYS05NzExLTkwMjIyMDFjZjRjY3gxNzgyNjU3NDg1NTI4NDgyMDAw parts=101069niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1070niks3-go-unit-tests> 2026-06-28 14:38:05.654 UTC [67906] ERROR:  relation "goose_db_version" does not exist at character 361071niks3-go-unit-tests> 2026-06-28 14:38:05.654 UTC [67906] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1072niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Completed upload id=11073niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001074niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1075niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Starting cleanup of old closures method=DELETE path=/api/closures1076niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Aborted multipart uploads count=01077niks3-go-unit-tests> 2026/06/28 14:38:05 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=01078niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_closures1079niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_objects1080niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=multipart_uploads1081niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (11.71ms)1082niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=closures1083niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (482.33µs)1084niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (661.17µs)1085niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (12.54ms)1086niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001087niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (2ms)1088niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (511.83µs)1089niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21090niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.49s)1091niks3-go-unit-tests> === CONT  TestClientMultipleUploads1092niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=objects1093niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001094niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.59s)1095niks3-go-unit-tests> === CONT  TestCacheStatsHandler1096niks3-go-unit-tests> 2026/06/28 14:38:05 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=01097niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_closures1098niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=pending_objects1099niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=multipart_uploads1100niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=closures1101niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Vacuumed table table=objects1102niks3-go-unit-tests> 2026-06-28 14:38:05.742 UTC [67912] ERROR:  relation "goose_db_version" does not exist at character 361103niks3-go-unit-tests> 2026-06-28 14:38:05.742 UTC [67912] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1104niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (6.38ms)1105niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (414.88µs)1106niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (686.67µs)1107niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (34.35ms)1108niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001109niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (991.25µs)1110niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (173.29µs)1111niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21112niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.47s)1113niks3-go-unit-tests> === CONT  TestCacheConfigHandler1114niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1115niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1116niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1117niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1118niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1119niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1120niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1121niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1122niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1123niks3-go-unit-tests> 2026-06-28 14:38:05.831 UTC [67915] ERROR:  relation "goose_db_version" does not exist at character 361124niks3-go-unit-tests> 2026-06-28 14:38:05.831 UTC [67915] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1125niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (6.87ms)1126niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (594.96µs)1127niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (1.32ms)1128niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (5.74ms)1129niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001130niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (936.38µs)1131niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (230.79µs)1132niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21133niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.47s)1134niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1135niks3-go-unit-tests> 2026-06-28 14:38:05.909 UTC [67918] ERROR:  relation "goose_db_version" does not exist at character 361136niks3-go-unit-tests> 2026-06-28 14:38:05.909 UTC [67918] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1137niks3-go-unit-tests> 2026-06-28 14:38:05.921 UTC [67919] ERROR:  relation "goose_db_version" does not exist at character 361138niks3-go-unit-tests> 2026-06-28 14:38:05.921 UTC [67919] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1139niks3-go-unit-tests> 2026-06-28 14:38:05.927 UTC [67920] ERROR:  relation "goose_db_version" does not exist at character 361140niks3-go-unit-tests> 2026-06-28 14:38:05.927 UTC [67920] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1141niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (7.65ms)1142niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (8.92ms)1143niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (460.54µs)1144niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (332.67µs)1145niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (689µs)1146niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (712.46µs)1147niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (3.03ms)1148niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001149niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20241026095416_initial_model.sql (6.55ms)1150niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (3.21ms)1151niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001152niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251210153512_drop_unused_gin_index.sql (509.92µs)1153niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.29ms)1154niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (1.1ms)1155niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (219.21µs)1156niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21157niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (310.75µs)1158niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21159niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20251218171726_add_pins.sql (928.33µs)1160niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Created nix-cache-info in bucket bucket=bucket291161niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists1162niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.49s)1163niks3-go-unit-tests> 2026/06/28 14:38:05 OK   20260628120000_add_object_size_and_stats.sql (6.53ms)1164niks3-go-unit-tests> 2026/06/28 14:38:05 goose: successfully migrated database to version: 202606281200001165niks3-go-unit-tests> 2026/06/28 14:38:05 OK   1_commit_pending_closure.sql (951µs)1166niks3-go-unit-tests> 2026/06/28 14:38:05 OK   2_object_stats_trigger.sql (359.92µs)1167niks3-go-unit-tests> 2026/06/28 14:38:05 goose: up to current file version: 21168niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received uploads request method=POST path=/api/pending_closures1169niks3-go-unit-tests> 2026/06/28 14:38:05 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1170niks3-go-unit-tests> 2026-06-28 14:38:06.037 UTC [67926] ERROR:  relation "goose_db_version" does not exist at character 361171niks3-go-unit-tests> 2026-06-28 14:38:06.037 UTC [67926] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1172niks3-go-unit-tests> 2026-06-28 14:38:06.054 UTC [67927] ERROR:  relation "goose_db_version" does not exist at character 361173niks3-go-unit-tests> 2026-06-28 14:38:06.054 UTC [67927] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1174niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (16.64ms)1175niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (457.54µs)1176niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (1.05ms)1177niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.52ms)1178niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001179niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.36ms)1180niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (298.92µs)1181niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21182niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1183niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.50s)1184niks3-go-unit-tests> === CONT  TestService_Rustfstest1185niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (6.65ms)1186niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (628.46µs)1187niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (713.67µs)1188niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (3.07ms)1189niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001190niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.21ms)1191niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (366.83µs)1192niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21193niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket321194niks3-go-unit-tests> 2026-06-28 14:38:06.158 UTC [67935] ERROR:  relation "goose_db_version" does not exist at character 361195niks3-go-unit-tests> 2026-06-28 14:38:06.158 UTC [67935] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1196niks3-go-unit-tests> 2026-06-28 14:38:06.158 UTC [67936] ERROR:  relation "goose_db_version" does not exist at character 361197niks3-go-unit-tests> 2026-06-28 14:38:06.158 UTC [67936] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1198niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (7.81ms)1199niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (7.41ms)1200niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (703.25µs)1201niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (360.29µs)1202niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (704.46µs)1203niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (671.29µs)1204niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.61ms)1205niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001206niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.98ms)1207niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001208niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (967.63µs)1209niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (743.71µs)1210niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (208.29µs)1211niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21212niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (252.67µs)1213niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21214niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket341215niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1216niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-67679-129173745/TestPinProtectsFromGC1827408790/001/store/m9hxag0p5i1d9v18rs7gap4h3ydz3nwn-pinned-file.txt1217niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-67679-129173745/TestPinProtectsFromGC1827408790/001/store/8c2ljdw0iy9lw3lvvq3s1bw9hcpn1xrb-unpinned-file.txt1218niks3-go-unit-tests> === NAME  TestClientCADerivations1219niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-67679-129173745/TestClientCADerivations1055794528/001/store/7dxka5accbsp099mm1h8gvvsi1lsbc91-ca-test1220niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.48s)1221niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload1222niks3-go-unit-tests> 2026-06-28 14:38:06.211 UTC [67944] ERROR:  relation "goose_db_version" does not exist at character 361223niks3-go-unit-tests> 2026-06-28 14:38:06.211 UTC [67944] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1224niks3-go-unit-tests> === NAME  TestClientCADerivations1225niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1226niks3-go-unit-tests> === NAME  TestClientMultipleUploads1227niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-67679-129173745/TestClientMultipleUploads3066426469/001/store/aks31svyz4ybg804ar68m8yx19j2rwis-test-file-0.txt1228niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (16.13ms)1229niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (518.21µs)1230niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (5.98ms)1231niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.52ms)1232niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001233niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.08ms)1234niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (358.58µs)1235niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21236niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1237niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: bound subjects configured but subject DN unavailable1238niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1239niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.47s)1240niks3-go-unit-tests> === CONT  TestIsValidCachePath1241niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo1242niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo1243niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars1244niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1245niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst1246niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst1247niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz1248niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz1249niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz21250niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz21251niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed1252niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed1253niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls1254niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls1255niks3-go-unit-tests> === RUN   TestIsValidCachePath/log1256niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log1257niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation1258niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation1259niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info1260niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info1261niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html1262niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html1263niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent1264niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent1265niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle1266niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle1267niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e1268niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e1269niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u1270niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u1271niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path1272niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path1273niks3-go-unit-tests> 2026-06-28 14:38:06.272 UTC [67955] ERROR:  relation "goose_db_version" does not exist at character 361274niks3-go-unit-tests> 2026-06-28 14:38:06.272 UTC [67955] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1275niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty1276niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty1277niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash1278niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash1279niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension1280niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension1281niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash1282niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash1283niks3-go-unit-tests> === CONT  TestClientWithDependencies1284niks3-go-unit-tests> === NAME  TestClientMultipleUploads1285niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-67679-129173745/TestClientMultipleUploads3066426469/001/store/h3p0anlwrlvfjpy9r4barwkyl1dccdj4-test-file-1.txt1286niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1287niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (9.74ms)1288niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1289niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading m9hxag0p5i1d9v18rs7gap4h3ydz3nwn-pinned-file.txt (128B)1290niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (703.29µs)1291niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (856.38µs)1292niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1293niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=1 count=11294niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 narinfos1295niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1296niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.48ms)1297niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001298niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.54ms)1299niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (558.79µs)1300niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21301niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.44s)1302niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1303niks3-go-unit-tests> 2026/06/28 14:38:06 INFO OIDC provider initialized name=test1304niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=11305niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Upload complete. (78ms)1306niks3-go-unit-tests> 2026-06-28 14:38:06.308 UTC [67965] ERROR:  relation "goose_db_version" does not exist at character 361307niks3-go-unit-tests> 2026-06-28 14:38:06.308 UTC [67965] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1308niks3-go-unit-tests> === NAME  TestClientMultipleUploads1309niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-67679-129173745/TestClientMultipleUploads3066426469/001/store/f7x83zf5fh86h7cjhwmrm6rr54gbviai-test-file-2.txt1310niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (7.45ms)1311niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (913.58µs)1312niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (1.11ms)1313niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.22ms)1314niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001315niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.58ms)1316niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (515.13µs)1317niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21318niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1319niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1320niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1321niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:06.342366Z","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(4)"}1322niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:06.342382Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket37, 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(4)"}1323niks3-go-unit-tests> 2026/06/28 14:38:06 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=Y2JjY2MwZDUtMjNjMy00ODM2LTkxZGQtMDNmZTIzMDc5NDQwLmY0YzY3YThlLTExNjYtNDkzZi1hMTk0LWE3YmZiZmI3Nzg5YngxNzgyNjU3NDg2MzM1NjE0MDAw1324niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=Y2JjY2MwZDUtMjNjMy00ODM2LTkxZGQtMDNmZTIzMDc5NDQwLmY0YzY3YThlLTExNjYtNDkzZi1hMTk0LWE3YmZiZmI3Nzg5YngxNzgyNjU3NDg2MzM1NjE0MDAw parts=11325niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.40s)1326niks3-go-unit-tests> === CONT  TestReadProxyNarinfo1327niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1328niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 7dxka5accbsp099mm1h8gvvsi1lsbc91-ca-test (144B)1329niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1330niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=1 count=11331niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 narinfos1332niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1333niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=11334niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Upload complete. (113ms)1335niks3-go-unit-tests> === NAME  TestClientCADerivations1336niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-67679-129173745/TestClientCADerivations1055794528/001/store/7dxka5accbsp099mm1h8gvvsi1lsbc91-ca-test1337niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1338niks3-go-unit-tests>         Compression: zstd1339niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1340niks3-go-unit-tests>         NarSize: 1441341niks3-go-unit-tests>         References: 1342niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-67679-129173745/TestClientCADerivations1055794528/001/store/m6cpmm5f2mxgdsrsgxr5af5n6hacw0xj-ca-test.drv1343niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1344niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1345niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1346niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1347niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1348niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1349niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 8c2ljdw0iy9lw3lvvq3s1bw9hcpn1xrb-unpinned-file.txt (128B)1350niks3-go-unit-tests> 2026-06-28 14:38:06.404 UTC [67981] ERROR:  relation "goose_db_version" does not exist at character 361351niks3-go-unit-tests> 2026-06-28 14:38:06.404 UTC [67981] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1352niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1353niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=2 count=11354niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 narinfos1355niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1356niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=21357niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Upload complete. (76ms)1358niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1359niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket29?endpoint=http://localhost:52446&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-67679-129173745/TestClientCADerivations1055794528/001/store'1360niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11361niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (7.05ms)1362niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.97s)1363niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1364niks3-go-unit-tests> === CONT  TestParseSingleRange/none1365niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1366niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1367niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1368niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1369niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1370niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1371niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1372niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1373niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1374niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1375niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1376niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1377niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1378niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1379niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1380niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1381niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1382niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1383niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1384niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1385niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1386niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1387niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1388niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1389niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1390niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1391niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1392niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1393niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1394niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (548.04µs)1395niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1396niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1397niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1398niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1399niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1400niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1401niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/1402niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1403niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/1404niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1405niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received request for more parts method=POST path=/1406niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1407niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/1408niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1409niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1410niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1411niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1412niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1413niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1414niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/1415niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1416niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (903.13µs)1417niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1418niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1419niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading f7x83zf5fh86h7cjhwmrm6rr54gbviai-test-file-2.txt (160B)1420niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading aks31svyz4ybg804ar68m8yx19j2rwis-test-file-0.txt (160B)1421niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading h3p0anlwrlvfjpy9r4barwkyl1dccdj4-test-file-1.txt (160B)1422niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1423niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=2 count=11424niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1425niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=3 count=11426niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1427niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=1 count=11428niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 3 narinfos1429niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1430niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.91ms)1431niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001432niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.4ms)1433niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=31434niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1435niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (576.04µs)1436niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21437niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=11438niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1439niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=21440niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Upload complete. (80ms)1441niks3-go-unit-tests> === NAME  TestClientMultipleUploads1442niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 116.933167ms1443niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.37s)1444niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1445niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received request for more parts method=POST path=/1446niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.74s)1447niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1448niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/1449niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received create pin request method=POST path=/api/pins/myapp1450niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1451niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-67679-129173745/TestPinProtectsFromGC1827408790/001/store/m9hxag0p5i1d9v18rs7gap4h3ydz3nwn-pinned-file.txt narinfo_key=m9hxag0p5i1d9v18rs7gap4h3ydz3nwn.narinfo1452niks3-go-unit-tests> 2026-06-28 14:38:06.453 UTC [67986] ERROR:  relation "goose_db_version" does not exist at character 361453niks3-go-unit-tests> 2026-06-28 14:38:06.453 UTC [67986] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1454niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Starting cleanup of old closures method=DELETE path=/api/closures1455niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Garbage collection started1456niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1457niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Aborted multipart uploads count=01458niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Force mode enabled - objects will be deleted immediately without grace period1459niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (5.07ms)1460niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (792.29µs)1461niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (1.65ms)1462niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (803.79µs)1463niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001464niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.17ms)1465niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (199.46µs)1466niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21467niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1468niks3-go-unit-tests> 2026-06-28 14:38:06.487 UTC [67991] ERROR:  relation "goose_db_version" does not exist at character 361469niks3-go-unit-tests> 2026-06-28 14:38:06.487 UTC [67991] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1470niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1471niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (49.57ms)1472niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (743.13µs)1473niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (2.2ms)1474niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (6.17ms)1475niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001476niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.07ms)1477niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (379.33µs)1478niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21479niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket401480niks3-go-unit-tests> 2026-06-28 14:38:06.573 UTC [67995] ERROR:  relation "goose_db_version" does not exist at character 361481niks3-go-unit-tests> 2026-06-28 14:38:06.573 UTC [67995] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1482niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (5.63ms)1483niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (1.26ms)1484niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (1.25ms)1485niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.62ms)1486niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001487niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.68ms)1488niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (426.04µs)1489niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21490niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1491niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1492niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1493niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1494niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1495niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1496niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1497niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1498niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1499niks3-go-unit-tests> 2026-06-28 14:38:06.654 UTC [68005] ERROR:  relation "goose_db_version" does not exist at character 361500niks3-go-unit-tests> 2026-06-28 14:38:06.654 UTC [68005] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1501niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1502niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1503niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (8.56ms)1504niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=Y2JjY2MwZDUtMjNjMy00ODM2LTkxZGQtMDNmZTIzMDc5NDQwLmZkZGE2MTNiLTg3ZWMtNDkxNi04ZjRiLTM4NWYyN2FlOTliY3gxNzgyNjU3NDg2NDc4NzM5MDAw parts=121505niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.49s)1506niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1507niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1508niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1509niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1510niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1511niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1512niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1513niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1514niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1515niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1516niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1517niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1518niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1519niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1520niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1521niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1522niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1523niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1524niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1525niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1526niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1527niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1528niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1529niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1530niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1531niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1532niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1533niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1534niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1535niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1536niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1537niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1538niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1539niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1540niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1541niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1542niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1543niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1544niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1545niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1546niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1547niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1548niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1549niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1550niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1551niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1552niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1553niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1554niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1555niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1556niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1557niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1558niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1559niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1560niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1561niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1562niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1563niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1564niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1565niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1566niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1567niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1568niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1569niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1570niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1571niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1572niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1573niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1574niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1575niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1576niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1577niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1578niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1579niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1580niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1581niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1582niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1583niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21584niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1585niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1586niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1587niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1588niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1589niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1590niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1591niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1592niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1593niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1594niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1595niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1596niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1597niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1598niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1599niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1600niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1601niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1602niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1603niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1604niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1605niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1606niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1607niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1608niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1609niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1610niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1611niks3-go-unit-tests> 2026/06/28 14:38:06 INFO OIDC auth successful provider=test1612niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1613niks3-go-unit-tests> 2026/06/28 14:38:06 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]1614niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1615niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1616niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.55ms)1617niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Authentication failed token_preview=eyJhbGciOi...HnQ34wvmpg 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]1618niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.32s)1619niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1620niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1621niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1622niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1623niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (15ms)1624niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.86ms)1625niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001626niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.66ms)1627niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (455.54µs)1628niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21629niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.02s)1630niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1631niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1632niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.28s)1633niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.36s)1634niks3-go-unit-tests> 2026/06/28 14:38:06 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=01635niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Vacuumed table table=pending_closures1636niks3-go-unit-tests> 2026-06-28 14:38:06.752 UTC [68009] ERROR:  relation "goose_db_version" does not exist at character 361637niks3-go-unit-tests> 2026-06-28 14:38:06.752 UTC [68009] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1638niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Vacuumed table table=pending_objects1639niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Vacuumed table table=multipart_uploads1640niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Vacuumed table table=closures1641niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (5.21ms)1642niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Vacuumed table table=objects1643niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (354.83µs)1644niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (701.46µs)1645niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.13ms)1646niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001647niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (892.25µs)1648niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (182.33µs)1649niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21650niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=201.980994ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1651niks3-go-unit-tests> === NAME  TestClientWithDependencies1652niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-67679-129173745/TestClientWithDependencies2660920466/001/store/l7a96dndi8b1vi5qynvrrrkzrm6c28ay-test-script1653niks3-go-unit-tests> 2026-06-28 14:38:06.799 UTC [68013] ERROR:  relation "goose_db_version" does not exist at character 361654niks3-go-unit-tests> 2026-06-28 14:38:06.799 UTC [68013] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1655niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (3.83ms)1656niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (468.75µs)1657niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (713.75µs)1658niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1659niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (1.2ms)1660niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001661niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.16ms)1662niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (243.17µs)1663niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21664niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1665niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1666niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading l7a96dndi8b1vi5qynvrrrkzrm6c28ay-test-script (136B)1667niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1668niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Signed narinfos id=1 count=11669niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Uploading 1 narinfos1670niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1671niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Completed upload id=11672niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Upload complete. (46ms)1673niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-67679-129173745/TestClientWithDependencies2660920466/001/store) requires matching store prefix1674niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.61s)1675niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1676niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=404.59498ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1677niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=829.32389ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1678niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01679niks3-go-unit-tests> === NAME  TestClientIntegration1680niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1681niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1682niks3-go-unit-tests> --- PASS: TestClientIntegration (2.83s)1683niks3-go-unit-tests> 2026/06/28 14:38:08 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.731745596s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1684niks3-go-unit-tests> 2026/06/28 14:38:08 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01685niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1686niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1687niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.87s)1688niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1689niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.34s)1690niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.35s)1691niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.50s)1692niks3-go-unit-tests> 2026/06/28 14:38:10 WARN Rate limiter enabled after throttle name=s3-test rate=51693niks3-go-unit-tests> 2026/06/28 14:38:10 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1694niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1695niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101696niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001697niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.48s)1698niks3-go-unit-tests> PASS1699niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:11.428728Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:52502"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}1700niks3-go-unit-tests> 2026-06-28 14:38:11.491 UTC [67714] LOG:  received smart shutdown request1701niks3-go-unit-tests> 2026-06-28 14:38:11.492 UTC [67714] LOG:  background worker "logical replication launcher" (PID 67721) exited with exit code 11702niks3-go-unit-tests> 2026-06-28 14:38:11.501 UTC [67716] LOG:  shutting down1703niks3-go-unit-tests> 2026-06-28 14:38:11.501 UTC [67716] LOG:  checkpoint starting: shutdown immediate1704niks3-go-unit-tests> 2026-06-28 14:38:12.530 UTC [67716] LOG:  checkpoint complete: wrote 13032 buffers (79.5%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.778 s, sync=0.250 s, total=1.030 s; sync files=14421, longest=0.006 s, average=0.001 s; distance=197509 kB, estimate=197509 kB; lsn=0/D5F41C0, redo lsn=0/D5F41C01705niks3-go-unit-tests> 2026-06-28 14:38:12.533 UTC [67714] LOG:  database system is shut down1706niks3-go-unit-tests> Running OIDC tests...1707niks3-go-unit-tests> === RUN   TestGlobMatch1708niks3-go-unit-tests> === PAUSE TestGlobMatch1709niks3-go-unit-tests> === RUN   TestAudienceForIssuer1710niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1711niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1712niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1713niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1714niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1715niks3-go-unit-tests> === RUN   TestValidateToken_Expired1716niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1717niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1718niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1719niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1720niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1721niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1722niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1723niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1724niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1725niks3-go-unit-tests> === CONT  TestGlobMatch1726niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1727niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1728niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1729niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1730niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1731niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1732niks3-go-unit-tests> === RUN   TestGlobMatch/*_1733niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1734niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1735niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1736niks3-go-unit-tests> === CONT  TestAudienceForIssuer1737niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1738niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1739niks3-go-unit-tests> === CONT  TestValidateToken_Expired1740niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1741niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1742niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1743niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1744niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1745niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1746niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1747niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1748niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1749niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1750niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1751niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1752niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1753niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1754niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1755niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1756niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1757niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1758niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1759niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1760niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1761niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1762niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1763niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1764niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1765niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1766niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1767niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01768niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01769niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1770niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1771niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1772niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1773niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1774niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1775niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1776niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1777niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1778niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1779niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1780niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1781niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1782niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1783niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1784niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1785niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1786niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1787niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1789niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1790niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1791niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1792niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1793niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1794niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01795niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1796niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1797niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1798niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1799niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1800niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1801niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1802niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1803niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1804niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1805niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1806niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1807niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1808niks3-go-unit-tests> === CONT  TestGlobMatch/*_1809niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1810niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1811niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1812niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1813niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1814niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1815niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1816niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1836niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=test1837niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=provider11838niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=test1839niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=test1840niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=test1841niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=test1842niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=provider11843niks3-go-unit-tests> 2026/06/28 14:38:13 INFO OIDC provider initialized name=provider21844niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.02s)1845niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.02s)1846niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.02s)1847niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.02s)1848niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.03s)1849niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.03s)1850niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)1851niks3-go-unit-tests> PASS1852niks3-go-unit-tests> Running hook tests...1853niks3-go-unit-tests> === RUN   TestSendPathsEmpty1854niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1855niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1856niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1857niks3-go-unit-tests> === RUN   TestQueueDeduplication1858niks3-go-unit-tests> === PAUSE TestQueueDeduplication1859niks3-go-unit-tests> === RUN   TestQueueRemove1860niks3-go-unit-tests> === PAUSE TestQueueRemove1861niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1862niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1863niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1864niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1865niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1866niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1867niks3-go-unit-tests> === RUN   TestServerClientIntegration1868niks3-go-unit-tests> === PAUSE TestServerClientIntegration1869niks3-go-unit-tests> === RUN   TestServerQueueError1870niks3-go-unit-tests> === PAUSE TestServerQueueError1871niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1872niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1873niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1874niks3-go-unit-tests>         PASS1875niks3-go-unit-tests>         1876niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1877niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1878niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1879niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1880niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1881niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1882niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1883niks3-go-unit-tests> === CONT  TestSendPathsEmpty1884niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1885niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1886niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1887niks3-go-unit-tests> === CONT  TestQueueRemove1888niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1889niks3-go-unit-tests> === CONT  TestQueueDeduplication1890niks3-go-unit-tests> === CONT  TestServerQueueError1891niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1892niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1893niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1894niks3-go-unit-tests> === CONT  TestServerClientIntegration1895niks3-go-unit-tests> 2026/06/28 14:38:13 ERROR Failed to queue paths error="permission denied" count=11896niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1897niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1898niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1899niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Upload queue status pending=21900niks3-go-unit-tests> 2026/06/28 14:38:13 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-67679-129173745/TestWorkerSkipsGCdPaths1688627077/002/nonexistent1901niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Uploading batch count=11902niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Upload queue status pending=21903niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Uploading batch count=21904niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1905niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Upload queue status pending=21906niks3-go-unit-tests> 2026/06/28 14:38:13 INFO Uploading batch count=11907niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1908niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1909niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1910niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1911niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1912niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1913niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1914niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)1915niks3-go-unit-tests> PASS19161917post-build step Upload coverage to codecov: ok1918Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests