nixbot

1this derivation will be built:2  /nix/store/65nn443sx6pxwxs61s11f5bhmrr7y187-niks3-go-unit-tests.drv3building '/nix/store/65nn443sx6pxwxs61s11f5bhmrr7y187-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/65nn443sx6pxwxs61s11f5bhmrr7y187-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  TestFileTokenMissing76niks3-go-unit-tests> === CONT  TestResolveStorePath77niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport78niks3-go-unit-tests> === CONT  TestConvertHashToNix3279niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3280niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3281niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format82niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format83niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format84niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format85niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix3286niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess87niks3-go-unit-tests> === CONT  TestDumpPathSingleFile88niks3-go-unit-tests> === CONT  TestEncodeNixBase3289niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)90niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand91niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)92niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails93niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash94niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash95niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input96niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter enabled after throttle name=server-test rate=597niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input98niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash99niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash100niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)101niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input102niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)103niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)104niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)105niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh106niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall107niks3-go-unit-tests> === CONT  TestRateLimiterFeedback108niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter109niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter110niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter111niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter112niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter113niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter114niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter115niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter116niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix117niks3-go-unit-tests> === CONT  TestDumpPathWriterError118niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)119niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer120niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists121niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists122niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing123niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing124niks3-go-unit-tests> === CONT  TestPartSizeForNAR125niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum126niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum127niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum128niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum129niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum130niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum131niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts132niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts133niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB134niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB135niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object136niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object137niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB138niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB139niks3-go-unit-tests> === CONT  TestCaseHackSuffix140niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)141niks3-go-unit-tests> === CONT  TestShellSplitErrors142niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)143niks3-go-unit-tests> === CONT  TestSetClientTLS144niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)145niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility146niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)147niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)148niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon149niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon150niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI151niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI152niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512153niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512154niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility155niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field156niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field157niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text158niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text159niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive160niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive161niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text162niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text163niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method164niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method165niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths166niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths167niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths168niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths169niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths170niks3-go-unit-tests> === CONT  TestParsePathInfoJSON171niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format172niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format173niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format174niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format175niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input176niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input177niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only178niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only179niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON180niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON181niks3-go-unit-tests> === CONT  TestFileTokenEmpty182niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)183niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format184niks3-go-unit-tests> === CONT  TestGetStorePathHash185niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path186niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path187niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error188niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error189niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error190niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error191niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error192niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error193niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format194niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)195niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)196niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)197niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)198niks3-go-unit-tests> === CONT  TestShellSplit199niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)200niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON201niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)202niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody203niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter enabled after throttle name=server-test rate=5204niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51530205niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter backed off name=server-test rate=5206niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51530207niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)208niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter209niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter enabled after throttle name=server-test rate=5210niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:51533211niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter backed off name=server-test rate=5212niks3-go-unit-tests> === CONT  TestStaticToken213niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)214niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches215niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert216niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert217niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA218niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA219niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport220niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport221niks3-go-unit-tests> === CONT  TestSetClientTLSErrors222niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file223niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)224niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken225niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file226niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file227niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file228niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file229niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file230niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file231niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file232niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter233niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter234niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter235niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter enabled after throttle name=server-test rate=5236niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:51539237niks3-go-unit-tests> 2026/06/28 10:13:20 WARN Rate limiter backed off name=server-test rate=5238niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)239niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)240niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)241niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)242niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)243niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists244niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing245niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)246niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum247niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB248niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB249niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object250niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum251niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts252niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum253niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)254niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI255niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)256niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)257niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)258niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)259niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)260niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)261niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)262niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)263niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)264niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)265niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)266niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512267niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon268niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field269niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)270niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)271niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)272niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)273niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)274niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths275niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method276niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text277niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive278niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text279niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)280niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)281niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)282niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)283niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)284niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)285niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format286niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths287niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)288niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)289niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)290niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only291niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input292niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format293niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON294niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error295niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)296niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)297niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)298niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)299niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)300niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)301niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error302niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path303niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error304niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert305niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport306niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)307niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)308niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)309niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)310niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)311niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA312niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file313niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file314niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file315niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file316niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)317niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)318niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)319niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)320niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)321niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)322niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.03s)323niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.03s)324niks3-go-unit-tests> 2026/06/28 10:13:20 http: TLS handshake error from 127.0.0.1:51545: read tcp 127.0.0.1:51532->127.0.0.1:51545: use of closed network connection325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.14s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.13s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.14s)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-46091-127378663/postgres206743324/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-46091-127378663/postgres206743324/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-46091-127378663/postgres206743324:5432 - no response364niks3-go-unit-tests> 2026-06-28 10:13:22.655 UTC [46126] 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 10:13:22.655 UTC [46126] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-46091-127378663/postgres206743324/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 10:13:22.656 UTC [46130] LOG:  database system was shut down at 2026-06-28 10:13:22 UTC367niks3-go-unit-tests> 2026-06-28 10:13:22.658 UTC [46126] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-46091-127378663/postgres206743324:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T10:13:24.484258Z","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(9)"}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   TestClientCADerivations384niks3-go-unit-tests> === PAUSE TestClientCADerivations385niks3-go-unit-tests> === RUN   TestClientErrorHandling386niks3-go-unit-tests> === PAUSE TestClientErrorHandling387niks3-go-unit-tests> === RUN   TestClientIntegration388niks3-go-unit-tests> === PAUSE TestClientIntegration389niks3-go-unit-tests> === RUN   TestClientMultipleUploads390niks3-go-unit-tests> === PAUSE TestClientMultipleUploads391niks3-go-unit-tests> === RUN   TestClientWithDependencies392niks3-go-unit-tests> === PAUSE TestClientWithDependencies393niks3-go-unit-tests> === RUN   TestPinProtectsFromGC394niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC395niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences396niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences397niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew398niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew399niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams400niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams401niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams402niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams403niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty404niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty405niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest406niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest407niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask408niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask409niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates410niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates411niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail412niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail413niks3-go-unit-tests> === RUN   TestService_healthCheckHandler414niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler415niks3-go-unit-tests> === RUN   TestGenerateLandingPage416niks3-go-unit-tests> === PAUSE TestGenerateLandingPage417niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug418niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug419niks3-go-unit-tests> === RUN   TestService_NativeMTLS420niks3-go-unit-tests> === PAUSE TestService_NativeMTLS421niks3-go-unit-tests> === RUN   TestServerTLSConfig422niks3-go-unit-tests> === PAUSE TestServerTLSConfig423niks3-go-unit-tests> === RUN   TestMultipartCleanup424niks3-go-unit-tests> === PAUSE TestMultipartCleanup425niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC426niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC427niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest428niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest429niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted430niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted431niks3-go-unit-tests> === RUN   TestParseSingleRange432niks3-go-unit-tests> === PAUSE TestParseSingleRange433niks3-go-unit-tests> === RUN   TestIsValidCachePath434niks3-go-unit-tests> === PAUSE TestIsValidCachePath435niks3-go-unit-tests> === RUN   TestReadProxyNarinfo436niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo437niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed438niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed439niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming440niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming441niks3-go-unit-tests> === RUN   TestReadProxy404442niks3-go-unit-tests> === PAUSE TestReadProxy404443niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath444niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath445niks3-go-unit-tests> === RUN   TestReadProxyHead446niks3-go-unit-tests> === PAUSE TestReadProxyHead447niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet448niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet449niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML450niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML451niks3-go-unit-tests> === RUN   TestReadProxyDisabled452niks3-go-unit-tests> === PAUSE TestReadProxyDisabled453niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest454niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest455niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload456niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload457niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists458niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists459niks3-go-unit-tests> === RUN   TestService_Rustfstest460niks3-go-unit-tests> === PAUSE TestService_Rustfstest461niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle462niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle463niks3-go-unit-tests> === RUN   TestProxyWriteTimeout464niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout465niks3-go-unit-tests> === RUN   TestIsValidUploadKey466niks3-go-unit-tests> === PAUSE TestIsValidUploadKey467niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys468niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys469niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody470niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody471niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler472niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler473niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler474niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler475niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity476niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity477niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered478niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered479niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT480niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT481niks3-go-unit-tests> === CONT  TestService_AuthMiddleware482niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest483niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload484niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC485niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest486niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody487niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming488niks3-go-unit-tests> === CONT  TestMultipartCleanup489niks3-go-unit-tests> === CONT  TestReadProxy404490niks3-go-unit-tests> === CONT  TestReadProxyDisabled491niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure492niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure493niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart494niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart495niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts496niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts497niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML498niks3-go-unit-tests> 2026-06-28 10:13:24.825 UTC [46200] ERROR:  relation "goose_db_version" does not exist at character 36499niks3-go-unit-tests> 2026-06-28 10:13:24.825 UTC [46200] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC500niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46203] ERROR:  relation "goose_db_version" does not exist at character 36501niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46203] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC502niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46202] ERROR:  relation "goose_db_version" does not exist at character 36503niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46202] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC504niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46201] ERROR:  relation "goose_db_version" does not exist at character 36505niks3-go-unit-tests> 2026-06-28 10:13:24.840 UTC [46201] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC506niks3-go-unit-tests> 2026-06-28 10:13:24.844 UTC [46204] ERROR:  relation "goose_db_version" does not exist at character 36507niks3-go-unit-tests> 2026-06-28 10:13:24.844 UTC [46204] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC508niks3-go-unit-tests> 2026-06-28 10:13:24.845 UTC [46205] ERROR:  relation "goose_db_version" does not exist at character 36509niks3-go-unit-tests> 2026-06-28 10:13:24.845 UTC [46205] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC510niks3-go-unit-tests> 2026-06-28 10:13:24.846 UTC [46207] ERROR:  relation "goose_db_version" does not exist at character 36511niks3-go-unit-tests> 2026-06-28 10:13:24.846 UTC [46207] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC512niks3-go-unit-tests> 2026-06-28 10:13:24.847 UTC [46206] ERROR:  relation "goose_db_version" does not exist at character 36513niks3-go-unit-tests> 2026-06-28 10:13:24.847 UTC [46206] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC514niks3-go-unit-tests> 2026-06-28 10:13:24.848 UTC [46208] ERROR:  relation "goose_db_version" does not exist at character 36515niks3-go-unit-tests> 2026-06-28 10:13:24.848 UTC [46208] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC516niks3-go-unit-tests> 2026-06-28 10:13:24.849 UTC [46209] ERROR:  relation "goose_db_version" does not exist at character 36517niks3-go-unit-tests> 2026-06-28 10:13:24.849 UTC [46209] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC518niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.44ms)519niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.41ms)520niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.08ms)521niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.89ms)522niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.4ms)523niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.33ms)524niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.35ms)525niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.55ms)526niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.54ms)527niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20241026095416_initial_model.sql (20.14ms)528niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (863.13µs)529niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (805.33µs)530niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (838.96µs)531niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (884.92µs)532niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (915.13µs)533niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (991.21µs)534niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (1.44ms)535niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (1.57ms)536niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (1.61ms)537niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251210153512_drop_unused_gin_index.sql (1.79ms)538niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.33ms)539niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726540niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.44ms)541niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726542niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.67ms)543niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726544niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.76ms)545niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726546niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.61ms)547niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726548niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.7ms)549niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726550niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (1.79ms)551niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726552niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (2.37ms)553niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726554niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (2.29ms)555niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726556niks3-go-unit-tests> 2026/06/28 10:13:24 OK   20251218171726_add_pins.sql (3.15ms)557niks3-go-unit-tests> 2026/06/28 10:13:24 goose: successfully migrated database to version: 20251218171726558niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27.83ms)559niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1560niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (25.7ms)561niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1562niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27.83ms)563niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1564niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27ms)565niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1566niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (28.2ms)567niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27.78ms)568niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1569niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27.86ms)570niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1571niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (27.9ms)572niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1573niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1574niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (26.27ms)575niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1576niks3-go-unit-tests> 2026/06/28 10:13:24 OK   1_commit_pending_closure.sql (26.46ms)577niks3-go-unit-tests> 2026/06/28 10:13:24 goose: up to current file version: 1578niks3-go-unit-tests> 2026/06/28 10:13:24 INFO Received uploads request method=POST path=/api/pending_closures579niks3-go-unit-tests> 2026/06/28 10:13:24 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"580niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.41s)581niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet582niks3-go-unit-tests> 2026/06/28 10:13:24 INFO Received uploads request method=POST path=/api/pending_closures583niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.41s)584niks3-go-unit-tests> === CONT  TestReadProxyHead585niks3-go-unit-tests> {"timestamp":"2026-06-28T10:13:24.91553Z","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(7)"}586niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.41s)587niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath588niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.38s)589niks3-go-unit-tests> === CONT  TestService_Rustfstest590niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.42s)591niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle592niks3-go-unit-tests> 2026/06/28 10:13:24 INFO Received uploads request method=POST path=/api/pending_closures593niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.42s)594niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity595niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received cleanup request method=DELETE path=/api/pending_closures596niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Aborted multipart uploads count=1597niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.54s)598niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT599niks3-go-unit-tests> 2026-06-28 10:13:25.104 UTC [46245] ERROR:  relation "goose_db_version" does not exist at character 36600niks3-go-unit-tests> 2026-06-28 10:13:25.104 UTC [46245] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC601niks3-go-unit-tests> 2026-06-28 10:13:25.105 UTC [46246] ERROR:  relation "goose_db_version" does not exist at character 36602niks3-go-unit-tests> 2026-06-28 10:13:25.105 UTC [46246] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC603niks3-go-unit-tests> 2026-06-28 10:13:25.109 UTC [46247] ERROR:  relation "goose_db_version" does not exist at character 36604niks3-go-unit-tests> 2026-06-28 10:13:25.109 UTC [46247] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC605niks3-go-unit-tests> 2026-06-28 10:13:25.109 UTC [46248] ERROR:  relation "goose_db_version" does not exist at character 36606niks3-go-unit-tests> 2026-06-28 10:13:25.109 UTC [46248] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC607niks3-go-unit-tests> 2026-06-28 10:13:25.110 UTC [46249] ERROR:  relation "goose_db_version" does not exist at character 36608niks3-go-unit-tests> 2026-06-28 10:13:25.110 UTC [46249] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC609niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete610niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ODVhN2Y1YmUtYjU3Zi00YzYyLWJkNDctNTQ2NzNlYzRkZTM0LjIxMmYxYzE1LTQzZmUtNDNmMy04OGU2LTFhM2JkMmZmYTBkMHgxNzgyNjQxNjA0OTIzMDYyMDAw parts=12611niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.62s)612niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered613niks3-go-unit-tests> 2026-06-28 10:13:25.132 UTC [46251] ERROR:  relation "goose_db_version" does not exist at character 36614niks3-go-unit-tests> 2026-06-28 10:13:25.132 UTC [46251] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC615niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC616niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:617niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A618niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B619niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)620niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)621niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects622niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.65s)623niks3-go-unit-tests> === CONT  TestIsValidCachePath624niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo625niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo626niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars627niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars628niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst629niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst630niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz631niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz632niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2633niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2634niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed635niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed636niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls637niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls638niks3-go-unit-tests> === RUN   TestIsValidCachePath/log639niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log640niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation641niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation642niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info643niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info644niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html645niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html646niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent647niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent648niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle650niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e651niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e652niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u654niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path656niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty657niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty658niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash660niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension661niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension662niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash663niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash664niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed665niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (32.72ms)666niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (39.6ms)667niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (13.46ms)668niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (27.84ms)669niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (27.98ms)670niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (920.04µs)671niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (1.01ms)672niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (811µs)673niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (828.33µs)674niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (829.92µs)675niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.04ms)676niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726677niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.27ms)678niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726679niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.38ms)680niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726681niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.44ms)682niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726683niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.95ms)684niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726685niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.75ms)686niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1687niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.44ms)688niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1689niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.16ms)690niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1691niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.33ms)692niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1693niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.55ms)694niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1695niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures696niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.26s)697niks3-go-unit-tests> === CONT  TestReadProxyNarinfo698niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.26s)699niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (14.35ms)700niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists701niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (1.59ms)702niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.27s)703niks3-go-unit-tests> === CONT  TestParseSingleRange704niks3-go-unit-tests> === RUN   TestParseSingleRange/none705niks3-go-unit-tests> === PAUSE TestParseSingleRange/none706niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit707niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit708niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored709niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored710niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash711niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash712niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty713niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty714niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start715niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start716niks3-go-unit-tests> === RUN   TestParseSingleRange/closed717niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed718niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended719niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended720niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size721niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size722niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix723niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix724niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size725niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size726niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte727niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte728niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF729niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF730niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF731niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF732niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted733niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (1.89ms)734niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726735niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.85ms)736niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1737niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.27s)738niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys739niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info740niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info741niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal742niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal743niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key744niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key745niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key746niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key747niks3-go-unit-tests> === CONT  TestIsValidUploadKey748niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo749niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo750niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst751niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst752niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz753niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz754niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain755niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain756niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing757niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing758niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log759niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log760niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file761niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file762niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name763niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name764niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark765niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark766niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals767niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals768niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation769niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation770niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output771niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output772niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info773niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info774niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html775niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html776niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type777niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type778niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type779niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type780niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type781niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type782niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal783niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal784niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar785niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar786niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute787niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute788niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key789niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key790niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type791niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type792niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler793niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures794niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete795niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete796niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ODVhN2Y1YmUtYjU3Zi00YzYyLWJkNDctNTQ2NzNlYzRkZTM0LmRjOTczZTNmLTJhOTktNDMzNi1iZGJjLTM2ODYzZGNlZGExMngxNzgyNjQxNjA1MTc4MDc0MDAw parts=10797niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete798niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed upload id=1799niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures800niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures801niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo802niks3-go-unit-tests> 2026/06/28 10:13:25 WARN Found objects in DB but missing from S3, will re-upload count=1803niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.40s)804niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew805niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)806niks3-go-unit-tests> === CONT  TestServerTLSConfig807niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA808niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA809niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file810niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file811niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file812niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file813niks3-go-unit-tests> === CONT  TestService_NativeMTLS814niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest815niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains816niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion817niks3-go-unit-tests> 2026-06-28 10:13:25.460 UTC [46265] ERROR:  relation "goose_db_version" does not exist at character 36818niks3-go-unit-tests> 2026-06-28 10:13:25.460 UTC [46265] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC819niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (31.91ms)820niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (387µs)821niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (662.5µs)822niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726823niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (820.42µs)824niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1825niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures826niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:827niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 20828niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 210829niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 210830niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.00s)831niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug832niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.47s)833niks3-go-unit-tests> === CONT  TestGenerateLandingPage834niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)835niks3-go-unit-tests> === CONT  TestService_healthCheckHandler836niks3-go-unit-tests> 2026-06-28 10:13:25.689 UTC [46270] ERROR:  relation "goose_db_version" does not exist at character 36837niks3-go-unit-tests> 2026-06-28 10:13:25.689 UTC [46270] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC838niks3-go-unit-tests> 2026-06-28 10:13:25.689 UTC [46271] ERROR:  relation "goose_db_version" does not exist at character 36839niks3-go-unit-tests> 2026-06-28 10:13:25.689 UTC [46271] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC840niks3-go-unit-tests> 2026-06-28 10:13:25.694 UTC [46272] ERROR:  relation "goose_db_version" does not exist at character 36841niks3-go-unit-tests> 2026-06-28 10:13:25.694 UTC [46272] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC842niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (10.78ms)843niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (541.04µs)844niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (691.88µs)845niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726846niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (832.17µs)847niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1848niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (13.55ms)849niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (309.67µs)850niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures851niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (4.28ms)852niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726853niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (18.51ms)854niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (489.21µs)855niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.18ms)856niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1857niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.76ms)858niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726859niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.57s)860niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail861niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)862niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates863niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)864niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask865niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)866niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest867niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)868niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty869niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)870niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams871niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)872niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams873niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)874niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler875niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (2.44ms)876niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1877niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete878niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete879niks3-go-unit-tests> {"timestamp":"2026-06-28T10:13:25.735505Z","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(8)"}880niks3-go-unit-tests> {"timestamp":"2026-06-28T10:13:25.735535Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket18, 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(8)"}881niks3-go-unit-tests> 2026/06/28 10:13:25 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=ODVhN2Y1YmUtYjU3Zi00YzYyLWJkNDctNTQ2NzNlYzRkZTM0LjIxZWM0MmU1LTJiMGUtNDRkYS05MzBlLWYzNzFhM2RlMGE1NXgxNzgyNjQxNjA1NzI0MzY2MDAw882niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ODVhN2Y1YmUtYjU3Zi00YzYyLWJkNDctNTQ2NzNlYzRkZTM0LjIxZWM0MmU1LTJiMGUtNDRkYS05MzBlLWYzNzFhM2RlMGE1NXgxNzgyNjQxNjA1NzI0MzY2MDAw parts=1883niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.56s)884niks3-go-unit-tests> === CONT  TestClientErrorHandling885niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath886niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath887niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken888niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken889niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable890niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable891niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences892niks3-go-unit-tests> 2026-06-28 10:13:25.742 UTC [46276] ERROR:  relation "goose_db_version" does not exist at character 36893niks3-go-unit-tests> 2026-06-28 10:13:25.742 UTC [46276] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC894niks3-go-unit-tests> 2026-06-28 10:13:25.743 UTC [46277] ERROR:  relation "goose_db_version" does not exist at character 36895niks3-go-unit-tests> 2026-06-28 10:13:25.743 UTC [46277] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC896niks3-go-unit-tests> 2026-06-28 10:13:25.744 UTC [46278] ERROR:  relation "goose_db_version" does not exist at character 36897niks3-go-unit-tests> 2026-06-28 10:13:25.744 UTC [46278] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC898niks3-go-unit-tests> 2026/06/28 10:13:25 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst899niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.62s)900niks3-go-unit-tests> === CONT  TestPinProtectsFromGC901niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (9.08ms)902niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (1.37ms)903niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (8.05ms)904niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (1.37ms)905niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726906niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (858.96µs)907niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (10.97ms)908niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.84ms)909niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1910niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (1.08ms)911niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (3.15ms)912niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726913niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (2.84ms)914niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726915niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (2.96ms)916niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1917niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (1.82ms)918niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1919niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures920niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures921niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures922niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.63s)923niks3-go-unit-tests> === CONT  TestClientWithDependencies924niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.65s)925niks3-go-unit-tests> === CONT  TestClientMultipleUploads926niks3-go-unit-tests> 2026-06-28 10:13:25.854 UTC [46286] ERROR:  relation "goose_db_version" does not exist at character 36927niks3-go-unit-tests> 2026-06-28 10:13:25.854 UTC [46286] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC928niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20241026095416_initial_model.sql (12.1ms)929niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251210153512_drop_unused_gin_index.sql (385.33µs)930niks3-go-unit-tests> 2026/06/28 10:13:25 OK   20251218171726_add_pins.sql (646.38µs)931niks3-go-unit-tests> 2026/06/28 10:13:25 goose: successfully migrated database to version: 20251218171726932niks3-go-unit-tests> 2026/06/28 10:13:25 OK   1_commit_pending_closure.sql (847.21µs)933niks3-go-unit-tests> 2026/06/28 10:13:25 goose: up to current file version: 1934niks3-go-unit-tests> 2026/06/28 10:13:25 WARN mTLS auth: subject not in bound subjects subject="CN=reader"935niks3-go-unit-tests> 2026/06/28 10:13:25 WARN mTLS auth: subject not in bound subjects subject="CN=writer"936niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.57s)937niks3-go-unit-tests> === CONT  TestClientIntegration938niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete939niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ODVhN2Y1YmUtYjU3Zi00YzYyLWJkNDctNTQ2NzNlYzRkZTM0Ljk3YWFiZWU5LWQ0ZDEtNDQyMy1hYmIwLWIxMDg4YjZjZDQzOXgxNzgyNjQxNjA1ODEwMzA1MDAw parts=10940niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete941niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Completed upload id=1942niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000943niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received uploads request method=POST path=/api/pending_closures944niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Starting cleanup of old closures method=DELETE path=/api/closures945niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Aborted multipart uploads count=0946niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=0947niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Vacuumed table table=pending_closures948niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Vacuumed table table=pending_objects949niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Vacuumed table table=multipart_uploads950niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Vacuumed table table=closures951niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Vacuumed table table=objects952niks3-go-unit-tests> 2026/06/28 10:13:25 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000953niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.79s)954niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC955niks3-go-unit-tests> 2026/06/28 10:13:25 INFO OIDC provider initialized name=test956niks3-go-unit-tests> 2026-06-28 10:13:26.013 UTC [46291] ERROR:  relation "goose_db_version" does not exist at character 36957niks3-go-unit-tests> 2026-06-28 10:13:26.013 UTC [46291] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC958niks3-go-unit-tests> 2026-06-28 10:13:26.037 UTC [46292] ERROR:  relation "goose_db_version" does not exist at character 36959niks3-go-unit-tests> 2026-06-28 10:13:26.037 UTC [46292] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC960niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (13.94ms)961niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (357.13µs)962niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (2.37ms)963niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 20251218171726964niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.21ms)965niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 1966niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket25967niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (7.72ms)968niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (477.79µs)969niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (766.25µs)970niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 20251218171726971niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (769.21µs)972niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 1973niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.54s)974niks3-go-unit-tests> === CONT  TestClientCADerivations975niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug976niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-46091-127378663/TestNARDeduplicationMetadataUploadBug1994551530/001/store/qwcpnn1pimh5g0wdncjrgjjn8b5hb1f8-file1.txt977niks3-go-unit-tests> 2026-06-28 10:13:26.215 UTC [46301] ERROR:  relation "goose_db_version" does not exist at character 36978niks3-go-unit-tests> 2026-06-28 10:13:26.215 UTC [46301] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC979niks3-go-unit-tests> 2026-06-28 10:13:26.230 UTC [46305] ERROR:  relation "goose_db_version" does not exist at character 36980niks3-go-unit-tests> 2026-06-28 10:13:26.230 UTC [46305] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC981niks3-go-unit-tests> 2026-06-28 10:13:26.231 UTC [46306] ERROR:  relation "goose_db_version" does not exist at character 36982niks3-go-unit-tests> 2026-06-28 10:13:26.231 UTC [46306] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC983niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (6.3ms)984niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (631.67µs)985niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (844.04µs)986niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 20251218171726987niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (839.04µs)988niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 1989niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket27990niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (7.29ms)991niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (932µs)992niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures993niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (1.09ms)994niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 20251218171726995niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.52ms)996niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 1997niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (11.59ms)998niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (415.46µs)999niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received cleanup request method=DELETE path=/api/pending_closures1000niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (662.88µs)1001niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261002niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Aborted multipart uploads count=01003niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (715.33µs)1004niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11005niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1006niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1007niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading qwcpnn1pimh5g0wdncjrgjjn8b5hb1f8-file1.txt (160B)1008niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1009niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11010niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1011niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1012niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received cleanup request method=DELETE path=/api/pending_closures1013niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Aborted multipart uploads count=11014niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1015niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11016niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (87ms)1017niks3-go-unit-tests> 2026-06-28 10:13:26.260 UTC [46305] ERROR:  Closure does not exist: id=11018niks3-go-unit-tests> 2026-06-28 10:13:26.260 UTC [46305] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1019niks3-go-unit-tests> 2026-06-28 10:13:26.260 UTC [46305] STATEMENT:  -- name: CommitPendingClosure :exec1020niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1021niks3-go-unit-tests> 	1022niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.54s)1023niks3-go-unit-tests> === CONT  TestCacheConfigHandler1024niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1025niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1026niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1027niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1028niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1029niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1030niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1031niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1032niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1033niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1034niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1035niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-46091-127378663/TestNARDeduplicationMetadataUploadBug1994551530/001/store/qwcpnn1pimh5g0wdncjrgjjn8b5hb1f8-file1.txt1036niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1037niks3-go-unit-tests>         Compression: zstd1038niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1039niks3-go-unit-tests>         NarSize: 1601040niks3-go-unit-tests>         References: 1041niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1042niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1043niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1044niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1045niks3-go-unit-tests> 2026-06-28 10:13:26.276 UTC [46313] ERROR:  relation "goose_db_version" does not exist at character 361046niks3-go-unit-tests> 2026-06-28 10:13:26.276 UTC [46313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1047niks3-go-unit-tests> 2026-06-28 10:13:26.319 UTC [46315] ERROR:  relation "goose_db_version" does not exist at character 361048niks3-go-unit-tests> 2026-06-28 10:13:26.319 UTC [46315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1049niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-46091-127378663/TestNARDeduplicationMetadataUploadBug1994551530/001/store/q1cawhc68q9fkak95vgridw2sklq4y6q-file2.txt1050niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (20.47ms)1051niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (552.92µs)1052niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (1.76ms)1053niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261054niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (792.96µs)1055niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11056niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (5.98ms)1057niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket301058niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (974.92µs)1059niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (7.49ms)1060niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261061niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (808.42µs)1062niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11063niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket311064niks3-go-unit-tests> 2026-06-28 10:13:26.347 UTC [46319] ERROR:  relation "goose_db_version" does not exist at character 361065niks3-go-unit-tests> 2026-06-28 10:13:26.347 UTC [46319] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1066niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1067niks3-go-unit-tests>     client_integration_test.go:645: Pinned store path: /nix/var/nix/builds/nix-46091-127378663/TestPinProtectsFromGC3059382506/001/store/pkrjkj05b4rdk63vjyvwsbx675714dq7-pinned-file.txt1068niks3-go-unit-tests>     client_integration_test.go:646: Unpinned store path: /nix/var/nix/builds/nix-46091-127378663/TestPinProtectsFromGC3059382506/001/store/php4vf5byw959q20kadll080b91njjcx-unpinned-file.txt1069niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (7.46ms)1070niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (692.67µs)1071niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (1.52ms)1072niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261073niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (864.5µs)1074niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11075niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket321076niks3-go-unit-tests> === NAME  TestClientMultipleUploads1077niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-46091-127378663/TestClientMultipleUploads1735265413/001/store/sx5fgypx16g41cy6jay5rvhshrqq5p5b-test-file-0.txt1078niks3-go-unit-tests> === NAME  TestClientIntegration1079niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-46091-127378663/TestClientIntegration2981914525/002/store/49djhw9yfa0y8vzlzxni456d1jhs68k3-test-file.txt1080niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1081niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1082niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1083niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=2 count=11084niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1085niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1086niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=21087niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (92ms)1088niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1089niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1090niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-46091-127378663/TestNARDeduplicationMetadataUploadBug1994551530/001/store/q1cawhc68q9fkak95vgridw2sklq4y6q-file2.txt1091niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1092niks3-go-unit-tests>         Compression: zstd1093niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1094niks3-go-unit-tests>         NarSize: 1601095niks3-go-unit-tests>         References: 1096niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1097niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1098niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1099niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1100niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.95s)1101niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1102niks3-go-unit-tests> 2026-06-28 10:13:26.456 UTC [46342] ERROR:  relation "goose_db_version" does not exist at character 361103niks3-go-unit-tests> 2026-06-28 10:13:26.456 UTC [46342] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1104niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.73s)1105niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1106niks3-go-unit-tests> === NAME  TestClientMultipleUploads1107niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-46091-127378663/TestClientMultipleUploads1735265413/001/store/bw876a33b2iw9x4lz68w3l3j9wv5p6xk-test-file-1.txt1108niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1109niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1110niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading pkrjkj05b4rdk63vjyvwsbx675714dq7-pinned-file.txt (128B)1111niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (22.1ms)1112niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (1.16ms)1113niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1114niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11115niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (2.29ms)1116niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261117niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1118niks3-go-unit-tests> 2026-06-28 10:13:26.487 UTC [46354] ERROR:  relation "goose_db_version" does not exist at character 361119niks3-go-unit-tests> 2026-06-28 10:13:26.487 UTC [46354] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1120niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1121niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.71ms)1122niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11123niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1124niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1125niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1126niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1127niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1128niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1129niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1130niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1131niks3-go-unit-tests> === CONT  TestProxyWriteTimeout1132niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo1133niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo1134niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar1135niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar1136niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar1137niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar1138niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size1139niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size1140niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1141niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/1142niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11143niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (102ms)1144niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (7.01ms)1145niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (838.13µs)1146niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (1.84ms)1147niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261148niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.41ms)1149niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11150niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created nix-cache-info in bucket bucket=bucket341151niks3-go-unit-tests> === NAME  TestClientMultipleUploads1152niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-46091-127378663/TestClientMultipleUploads1735265413/001/store/817h7z5ch5yf62j5gyfxhx98zg759f3b-test-file-2.txt1153niks3-go-unit-tests> 2026-06-28 10:13:26.553 UTC [46364] ERROR:  relation "goose_db_version" does not exist at character 361154niks3-go-unit-tests> 2026-06-28 10:13:26.553 UTC [46364] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1155niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1156niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (14.37ms)1157niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1158niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 49djhw9yfa0y8vzlzxni456d1jhs68k3-test-file.txt (152B)1159niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (764.21µs)1160niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (1.23ms)1161niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261162niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1163niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11164niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1165niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.74ms)1166niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11167niks3-go-unit-tests> 2026/06/28 10:13:26 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1168niks3-go-unit-tests> 2026/06/28 10:13:26 WARN mTLS auth: bound subjects configured but subject DN unavailable1169niks3-go-unit-tests> 2026/06/28 10:13:26 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1170niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.33s)1171niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1172niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received request for more parts method=POST path=/1173niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1174niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11175niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (120ms)1176niks3-go-unit-tests> === NAME  TestClientIntegration1177niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1178niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-46091-127378663/TestClientIntegration2981914525/002/store/49djhw9yfa0y8vzlzxni456d1jhs68k3-test-file.txt1179niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1180niks3-go-unit-tests>         Compression: zstd1181niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11182niks3-go-unit-tests>         NarSize: 1521183niks3-go-unit-tests>         References: 1184niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11185niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1186niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1187niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1188niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1189niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1190niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete multipart upload request method=POST path=/1191niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1192niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1193niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading php4vf5byw959q20kadll080b91njjcx-unpinned-file.txt (128B)1194niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1195niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=2 count=11196niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1197niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1198niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=21199niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (89ms)1200niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1201niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1202niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1203niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1204niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1205niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1206niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1207niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1208niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1209niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1210niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1211niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1212niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1213niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1214niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1215niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1216niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1217niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21218niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1219niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1220niks3-go-unit-tests> === CONT  TestParseSingleRange/none1221niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1222niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1223niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1224niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1225niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1226niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1227niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1228niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1229niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1230niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1231niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1232niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1233niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1234niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1235niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1236niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1237niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1238niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1239niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1240niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1241niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1242niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1243niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1244niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1245niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1246niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1247niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1248niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1249niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1250niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1251niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1252niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1253niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1254niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1255niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1256niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1257niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1258niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1259niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1260niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1261niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1262niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1263niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1264niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1265niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1266niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1267niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1268niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1269niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1270niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1271niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/1272niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1273niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete multipart upload request method=POST path=/1274niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1275niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received request for more parts method=POST path=/1276niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1277niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/1278niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1279niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1280niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1281niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1282niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1283niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1284niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1285niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1286niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1287niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1288niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1289niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1290niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1291niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1292niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1293niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1294niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1295niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1296niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1297niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1298niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1299niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1300niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1301niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1302niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1303niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1304niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1305niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1306niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1307niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1308niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1309niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1310niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1311niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1312niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1313niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1314niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1315niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1316niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1317niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1318niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1319niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1320niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1321niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1322niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1323niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1324niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1325niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1326niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1327niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1328niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1329niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1330niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1331niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1332niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1333niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1334niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1335niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1336niks3-go-unit-tests> === NAME  TestClientWithDependencies1337niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-46091-127378663/TestClientWithDependencies2307064856/001/store/96gr6s6wh7m9qfwrfva0r0qz89iybgx8-test-script1338niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Starting cleanup of old closures method=DELETE path=/api/closures1339niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1340niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Garbage collection started1341niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Aborted multipart uploads count=01342niks3-go-unit-tests> 2026-06-28 10:13:26.647 UTC [46387] ERROR:  relation "goose_db_version" does not exist at character 361343niks3-go-unit-tests> 2026-06-28 10:13:26.647 UTC [46387] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1344niks3-go-unit-tests> 2026/06/28 10:13:26 WARN Force mode enabled - objects will be deleted immediately without grace period1345niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1346niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1347niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1348niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 817h7z5ch5yf62j5gyfxhx98zg759f3b-test-file-2.txt (160B)1349niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading sx5fgypx16g41cy6jay5rvhshrqq5p5b-test-file-0.txt (160B)1350niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading bw876a33b2iw9x4lz68w3l3j9wv5p6xk-test-file-1.txt (160B)1351niks3-go-unit-tests> 2026-06-28 10:13:26.657 UTC [46389] ERROR:  relation "goose_db_version" does not exist at character 361352niks3-go-unit-tests> 2026-06-28 10:13:26.657 UTC [46389] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1353niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1354niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=2 count=11355niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1356niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=3 count=11357niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1358niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11359niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 3 narinfos1360niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (5.61ms)1361niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (1.38ms)1362niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1363niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11364niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (2.54ms)1365niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261366niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1367niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=21368niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1369niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=31370niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (108ms)1371niks3-go-unit-tests> === NAME  TestClientMultipleUploads1372niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 150.477166ms1373niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (2.32ms)1374niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11375niks3-go-unit-tests> 2026/06/28 10:13:26 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1376niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.22s)1377niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1378niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (11.79ms)1379niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (1.6ms)1380niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (2.41ms)1381niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261382niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.85s)1383niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1384niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (2.31ms)1385niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11386niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received create pin request method=POST path=/api/pins/myapp1387niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.22s)1388niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1389niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1390niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1391niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1392niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1393niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1394niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1395niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1396niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1397niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1398niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-46091-127378663/TestPinProtectsFromGC3059382506/001/store/pkrjkj05b4rdk63vjyvwsbx675714dq7-pinned-file.txt narinfo_key=pkrjkj05b4rdk63vjyvwsbx675714dq7.narinfo1399niks3-go-unit-tests> 2026/06/28 10:13:26 INFO OIDC auth successful provider=test1400niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1401niks3-go-unit-tests> 2026/06/28 10:13:26 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]1402niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1403niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1404niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Starting cleanup of old closures method=DELETE path=/api/closures1405niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Garbage collection started1406niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Aborted multipart uploads count=01407niks3-go-unit-tests> 2026/06/28 10:13:26 WARN Force mode enabled - objects will be deleted immediately without grace period1408niks3-go-unit-tests> 2026/06/28 10:13:26 WARN Authentication failed token_preview=eyJhbGciOi...GglxY99wlw 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]1409niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1410niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1411niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1412niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1413niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1414niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1415niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1416niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1417niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1418niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.52s)1419niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1420niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1421niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1422niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1423niks3-go-unit-tests> === NAME  TestClientWithDependencies1424niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1425niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1426niks3-go-unit-tests> === NAME  TestClientCADerivations1427niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-46091-127378663/TestClientCADerivations2307678743/001/store/kyar9pj3r6f0bw10azh6j766nd8s7cs1-ca-test1428niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1429niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 96gr6s6wh7m9qfwrfva0r0qz89iybgx8-test-script (136B)1430niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1431niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11432niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1433niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1434niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11435niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (54ms)1436niks3-go-unit-tests> 2026-06-28 10:13:26.781 UTC [46404] ERROR:  relation "goose_db_version" does not exist at character 361437niks3-go-unit-tests> 2026-06-28 10:13:26.781 UTC [46404] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1438niks3-go-unit-tests> === NAME  TestClientWithDependencies1439niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-46091-127378663/TestClientWithDependencies2307064856/001/store) requires matching store prefix1440niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.98s)1441niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (6.76ms)1442niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (434.79µs)1443niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (851.29µs)1444niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261445niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (1.53ms)1446niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11447niks3-go-unit-tests> === NAME  TestClientCADerivations1448niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1449niks3-go-unit-tests> 2026-06-28 10:13:26.816 UTC [46409] ERROR:  relation "goose_db_version" does not exist at character 361450niks3-go-unit-tests> 2026-06-28 10:13:26.816 UTC [46409] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1451niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.03s)1452niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1453niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1454niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.33s)1455niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20241026095416_initial_model.sql (3.17ms)1456niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251210153512_drop_unused_gin_index.sql (583.58µs)1457niks3-go-unit-tests> 2026/06/28 10:13:26 OK   20251218171726_add_pins.sql (853.54µs)1458niks3-go-unit-tests> 2026/06/28 10:13:26 goose: successfully migrated database to version: 202512181717261459niks3-go-unit-tests> 2026/06/28 10:13:26 OK   1_commit_pending_closure.sql (885.83µs)1460niks3-go-unit-tests> 2026/06/28 10:13:26 goose: up to current file version: 11461niks3-go-unit-tests> 2026/06/28 10:13:26 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_closures1462niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received uploads request method=POST path=/api/pending_closures1463niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1464niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading kyar9pj3r6f0bw10azh6j766nd8s7cs1-ca-test (144B)1465niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1466niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Signed narinfos id=1 count=11467niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Uploading 1 narinfos1468niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1469niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Completed upload id=11470niks3-go-unit-tests> 2026/06/28 10:13:26 INFO Upload complete. (66ms)1471niks3-go-unit-tests> === NAME  TestClientCADerivations1472niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-46091-127378663/TestClientCADerivations2307678743/001/store/kyar9pj3r6f0bw10azh6j766nd8s7cs1-ca-test1473niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1474niks3-go-unit-tests>         Compression: zstd1475niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1476niks3-go-unit-tests>         NarSize: 1441477niks3-go-unit-tests>         References: 1478niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-46091-127378663/TestClientCADerivations2307678743/001/store/yxcw4h7apnjv706gixa43bg3dpi1n2f7-ca-test.drv1479niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1480niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1481niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1482niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1483niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket34?endpoint=http://localhost:51548&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-46091-127378663/TestClientCADerivations2307678743/001/store'1484niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11485niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.88s)1486niks3-go-unit-tests> 2026/06/28 10:13:26 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=189.301917ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1487niks3-go-unit-tests> 2026/06/28 10:13:26 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1488niks3-go-unit-tests> 2026/06/28 10:13:27 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=01489niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=pending_closures1490niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=pending_objects1491niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=multipart_uploads1492niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=closures1493niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=objects1494niks3-go-unit-tests> 2026/06/28 10:13:27 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=01495niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=pending_closures1496niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=pending_objects1497niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=multipart_uploads1498niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=closures1499niks3-go-unit-tests> 2026/06/28 10:13:27 INFO Vacuumed table table=objects1500niks3-go-unit-tests> 2026/06/28 10:13:27 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=402.058588ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1501niks3-go-unit-tests> 2026/06/28 10:13:27 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=821.727952ms 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 10:13:28 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.6955878s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1503niks3-go-unit-tests> 2026/06/28 10:13:28 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01504niks3-go-unit-tests> === NAME  TestClientIntegration1505niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1506niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1507niks3-go-unit-tests> --- PASS: TestClientIntegration (2.76s)1508niks3-go-unit-tests> 2026/06/28 10:13:28 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01509niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1510niks3-go-unit-tests>     client_integration_test.go:708: Pin successfully protected closure from garbage collection1511niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.95s)1512niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1513niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.19s)1514niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.30s)1515niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.40s)1516niks3-go-unit-tests> 2026/06/28 10:13:30 WARN Rate limiter enabled after throttle name=s3-test rate=51517niks3-go-unit-tests> 2026/06/28 10:13:30 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1518niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1519niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101520niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001521niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.25s)1522niks3-go-unit-tests> PASS1523niks3-go-unit-tests> 2026-06-28 10:13:30.737 UTC [46126] LOG:  received smart shutdown request1524niks3-go-unit-tests> 2026-06-28 10:13:30.738 UTC [46126] LOG:  background worker "logical replication launcher" (PID 46133) exited with exit code 11525niks3-go-unit-tests> 2026-06-28 10:13:30.760 UTC [46128] LOG:  shutting down1526niks3-go-unit-tests> 2026-06-28 10:13:30.760 UTC [46128] LOG:  checkpoint starting: shutdown immediate1527niks3-go-unit-tests> 2026-06-28 10:13:31.770 UTC [46128] LOG:  checkpoint complete: wrote 13395 buffers (81.8%); 0 WAL file(s) added, 0 removed, 10 recycled; write=0.798 s, sync=0.210 s, total=1.011 s; sync files=12707, longest=0.001 s, average=0.001 s; distance=174313 kB, estimate=174313 kB; lsn=0/BF4D138, redo lsn=0/BF4D1381528niks3-go-unit-tests> 2026-06-28 10:13:31.773 UTC [46126] LOG:  database system is shut down1529niks3-go-unit-tests> Running OIDC tests...1530niks3-go-unit-tests> === RUN   TestGlobMatch1531niks3-go-unit-tests> === PAUSE TestGlobMatch1532niks3-go-unit-tests> === RUN   TestAudienceForIssuer1533niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1534niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1535niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1536niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1537niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1538niks3-go-unit-tests> === RUN   TestValidateToken_Expired1539niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1540niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1541niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1542niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1543niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1544niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1545niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1546niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1547niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1548niks3-go-unit-tests> === CONT  TestGlobMatch1549niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1550niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1551niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1552niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1553niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1554niks3-go-unit-tests> === CONT  TestAudienceForIssuer1555niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1556niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1557niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1558niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1559niks3-go-unit-tests> === RUN   TestGlobMatch/*_1560niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1561niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1562niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1563niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1564niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1565niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1566niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1567niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1568niks3-go-unit-tests> === CONT  TestValidateToken_Expired1569niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1570niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1571niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1572niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1573niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1574niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1575niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1576niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1577niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1578niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1579niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1580niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1581niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1582niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1583niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1584niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1585niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1586niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1587niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1588niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1589niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1590niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01591niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01592niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1593niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1594niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1595niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1596niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1597niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1598niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1599niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1600niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1601niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1602niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1603niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1604niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1605niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1606niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1607niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1608niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1609niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1610niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1611niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1612niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1613niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1614niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1615niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1616niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1617niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1618niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1619niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1620niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1621niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01622niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1623niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1624niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1625niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1626niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1627niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1628niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1629niks3-go-unit-tests> === CONT  TestGlobMatch/*_1630niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1631niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1632niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1633niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1634niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1635niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1636niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1637niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1638niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1639niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1640niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1641niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1642niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1643niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1644niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1645niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1646niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1647niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1648niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1649niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1650niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1651niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1652niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1653niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1654niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1655niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1656niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1657niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1658niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1659niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=test1660niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=test1661niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=test1662niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=provider11663niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=test1664niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=provider21665niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=test1666niks3-go-unit-tests> 2026/06/28 10:13:32 INFO OIDC provider initialized name=provider11667niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1668niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1669niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1670niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1671niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1672niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1673niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1674niks3-go-unit-tests> PASS1675niks3-go-unit-tests> Running hook tests...1676niks3-go-unit-tests> === RUN   TestSendPathsEmpty1677niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1678niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1679niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1680niks3-go-unit-tests> === RUN   TestQueueDeduplication1681niks3-go-unit-tests> === PAUSE TestQueueDeduplication1682niks3-go-unit-tests> === RUN   TestQueueRemove1683niks3-go-unit-tests> === PAUSE TestQueueRemove1684niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1685niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1686niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1687niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1688niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1689niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1690niks3-go-unit-tests> === RUN   TestServerClientIntegration1691niks3-go-unit-tests> === PAUSE TestServerClientIntegration1692niks3-go-unit-tests> === RUN   TestServerQueueError1693niks3-go-unit-tests> === PAUSE TestServerQueueError1694niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1695niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1696niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1697niks3-go-unit-tests>         PASS1698niks3-go-unit-tests>         1699niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1700niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1701niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1702niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1703niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1704niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1705niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1706niks3-go-unit-tests> === CONT  TestSendPathsEmpty1707niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1708niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1709niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1710niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1711niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1712niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1713niks3-go-unit-tests> === CONT  TestServerQueueError1714niks3-go-unit-tests> === CONT  TestServerClientIntegration1715niks3-go-unit-tests> === CONT  TestQueueRemove1716niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1717niks3-go-unit-tests> === CONT  TestQueueDeduplication1718niks3-go-unit-tests> 2026/06/28 10:13:32 ERROR Failed to queue paths error="permission denied" count=11719niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1720niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1721niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1722niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Upload queue status pending=21723niks3-go-unit-tests> 2026/06/28 10:13:32 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-46091-127378663/TestWorkerSkipsGCdPaths415358130/002/nonexistent1724niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1725niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Uploading batch count=11726niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1727niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1728niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1729niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Upload queue status pending=21730niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Uploading batch count=21731niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Upload queue status pending=21732niks3-go-unit-tests> 2026/06/28 10:13:32 INFO Uploading batch count=11733niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1734niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1735niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1736niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1737niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)1738niks3-go-unit-tests> PASS17391740post-build step Upload coverage to codecov: ok1741Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests