1this derivation will be built:2 /nix/store/v3s580wgb8vhfxlm6h4g1a7cayzhgzh3-niks3-go-unit-tests.drv3building '/nix/store/v3s580wgb8vhfxlm6h4g1a7cayzhgzh3-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/v3s580wgb8vhfxlm6h4g1a7cayzhgzh3-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 TestRegisterUploadedObjectReusesConnections9niks3-go-unit-tests> === PAUSE TestRegisterUploadedObjectReusesConnections10niks3-go-unit-tests> === RUN TestCaseHackSuffix11niks3-go-unit-tests> === PAUSE TestCaseHackSuffix12niks3-go-unit-tests> === RUN TestFilterOversizedClosures13niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures14niks3-go-unit-tests> === RUN TestUploadMultipart_PartsInParallel15niks3-go-unit-tests> === PAUSE TestUploadMultipart_PartsInParallel16niks3-go-unit-tests> === RUN TestPartSizeForNAR17niks3-go-unit-tests> === PAUSE TestPartSizeForNAR18niks3-go-unit-tests> === RUN TestUploadMultipart_SupersededByPeer19niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer20niks3-go-unit-tests> === RUN TestDumpPathCaseHackMatchesNix21niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackMatchesNix (0.28s)22niks3-go-unit-tests> === RUN TestDumpPathCaseHackCollision23niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackCollision (0.00s)24niks3-go-unit-tests> === RUN TestDumpPathMatchesNix25niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix26niks3-go-unit-tests> === RUN TestDumpPathSingleFile27niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile28niks3-go-unit-tests> === RUN TestDumpPathWriterError29niks3-go-unit-tests> === PAUSE TestDumpPathWriterError30niks3-go-unit-tests> === RUN TestEncodeNixBase3231niks3-go-unit-tests> === PAUSE TestEncodeNixBase3232niks3-go-unit-tests> === RUN TestEncodeNixBase32WithRealHash33niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash34niks3-go-unit-tests> === RUN TestConvertHashToNix3235niks3-go-unit-tests> === PAUSE TestConvertHashToNix3236niks3-go-unit-tests> === RUN TestGetStorePathHash37niks3-go-unit-tests> === PAUSE TestGetStorePathHash38niks3-go-unit-tests> === RUN TestPathInfoHashCompatibility39niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility40niks3-go-unit-tests> === RUN TestParsePathInfoJSON41niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON42niks3-go-unit-tests> === RUN TestParsePathInfoJSONMultiplePaths43niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths44niks3-go-unit-tests> === RUN TestPathInfoCACompatibility45niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility46niks3-go-unit-tests> === RUN TestRateLimiterFeedback47niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback48niks3-go-unit-tests> === RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess49niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess50niks3-go-unit-tests> === RUN TestResolveStorePath51niks3-go-unit-tests> === PAUSE TestResolveStorePath52niks3-go-unit-tests> === RUN TestDoWithRetry_BodyReplayedViaGetBody53niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody54niks3-go-unit-tests> === RUN TestShellSplit55niks3-go-unit-tests> === PAUSE TestShellSplit56niks3-go-unit-tests> === RUN TestShellSplitErrors57niks3-go-unit-tests> === PAUSE TestShellSplitErrors58niks3-go-unit-tests> === RUN TestStreamPushReportsEveryPath59niks3-go-unit-tests> === PAUSE TestStreamPushReportsEveryPath60niks3-go-unit-tests> === RUN TestStreamPushBatchesUnderLoad61niks3-go-unit-tests> === PAUSE TestStreamPushBatchesUnderLoad62niks3-go-unit-tests> === RUN TestStreamPushIsolatesFailures63niks3-go-unit-tests> === PAUSE TestStreamPushIsolatesFailures64niks3-go-unit-tests> === RUN TestStreamPushGivesUpOnDeadServer65niks3-go-unit-tests> === PAUSE TestStreamPushGivesUpOnDeadServer66niks3-go-unit-tests> === RUN TestStreamPushRequestLine67niks3-go-unit-tests> === PAUSE TestStreamPushRequestLine68niks3-go-unit-tests> === RUN TestStreamPushReportsSignatures69niks3-go-unit-tests> === PAUSE TestStreamPushReportsSignatures70niks3-go-unit-tests> === RUN TestClientSignaturesByStorePath71niks3-go-unit-tests> === PAUSE TestClientSignaturesByStorePath72niks3-go-unit-tests> === RUN TestSetClientTLS73niks3-go-unit-tests> === PAUSE TestSetClientTLS74niks3-go-unit-tests> === RUN TestSetClientTLSDoesNotMutateDefaultTransport75niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport76niks3-go-unit-tests> === RUN TestSetClientTLSErrors77niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors78niks3-go-unit-tests> === RUN TestStaticToken79niks3-go-unit-tests> === PAUSE TestStaticToken80niks3-go-unit-tests> === RUN TestFileTokenReadsAndCaches81niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches82niks3-go-unit-tests> === RUN TestFileTokenMissing83niks3-go-unit-tests> === PAUSE TestFileTokenMissing84niks3-go-unit-tests> === RUN TestFileTokenEmpty85niks3-go-unit-tests> === PAUSE TestFileTokenEmpty86niks3-go-unit-tests> === RUN TestScriptTokenNoExpiryRerunsEveryCall87niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall88niks3-go-unit-tests> === RUN TestScriptTokenCachesUntilRefresh89niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh90niks3-go-unit-tests> === RUN TestScriptTokenEmptyToken91niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken92niks3-go-unit-tests> === RUN TestScriptTokenBadJSON93niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON94niks3-go-unit-tests> === RUN TestScriptTokenScriptFails95niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails96niks3-go-unit-tests> === RUN TestScriptTokenEmptyCommand97niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand98niks3-go-unit-tests> === CONT TestDoServerRequestAttachesToken99niks3-go-unit-tests> === CONT TestScriptTokenEmptyCommand100niks3-go-unit-tests> === CONT TestShellSplit101niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)102niks3-go-unit-tests> === CONT TestStreamPushRequestLine103niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)104niks3-go-unit-tests> === CONT TestStreamPushGivesUpOnDeadServer105niks3-go-unit-tests> === CONT TestSetClientTLSDoesNotMutateDefaultTransport106niks3-go-unit-tests> === CONT TestStreamPushIsolatesFailures107niks3-go-unit-tests> === CONT TestSetClientTLSErrors108niks3-go-unit-tests> === CONT TestScriptTokenScriptFails109niks3-go-unit-tests> 2026/10/01 09:51:58 ERROR Upload failed error="bad path" count=3110niks3-go-unit-tests> --- PASS: TestStreamPushIsolatesFailures (0.00s)111niks3-go-unit-tests> === CONT TestScriptTokenNoExpiryRerunsEveryCall112niks3-go-unit-tests> 2026/10/01 09:51:58 ERROR Upload failed error="connection refused" count=20113niks3-go-unit-tests> 2026/10/01 09:51:58 ERROR Server seems unavailable, giving up on batch untried=17114niks3-go-unit-tests> --- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)115niks3-go-unit-tests> === CONT TestFileTokenEmpty116niks3-go-unit-tests> 2026/10/01 09:51:58 ERROR Upload failed error=boom count=1117niks3-go-unit-tests> === CONT TestScriptTokenBadJSON118niks3-go-unit-tests> === CONT TestScriptTokenEmptyToken119niks3-go-unit-tests> === CONT TestScriptTokenCachesUntilRefresh120niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)121niks3-go-unit-tests> === CONT TestFileTokenMissing122niks3-go-unit-tests> === RUN TestSetClientTLSErrors/missing_cert_file123niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file124niks3-go-unit-tests> === RUN TestSetClientTLSErrors/missing_key_file125niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file126niks3-go-unit-tests> === RUN TestSetClientTLSErrors/missing_ca_file127niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file128niks3-go-unit-tests> === RUN TestSetClientTLSErrors/invalid_ca_file129niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file130niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)131niks3-go-unit-tests> === CONT TestStaticToken132niks3-go-unit-tests> === CONT TestFileTokenReadsAndCaches133niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)134niks3-go-unit-tests> === CONT TestPathInfoCACompatibility135niks3-go-unit-tests> === RUN TestPathInfoCACompatibility/null_ca_field136niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field137niks3-go-unit-tests> === RUN TestPathInfoCACompatibility/old_string_format_-_text138niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text139niks3-go-unit-tests> === RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive140niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)141niks3-go-unit-tests> === CONT TestDoWithRetry_BodyReplayedViaGetBody142niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive143niks3-go-unit-tests> === RUN TestPathInfoCACompatibility/new_structured_format_-_text144niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text145niks3-go-unit-tests> === RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method146niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method147niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)148niks3-go-unit-tests> === CONT TestResolveStorePath149niks3-go-unit-tests> === CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess150niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter enabled after throttle name=server-test rate=5151niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)152niks3-go-unit-tests> === CONT TestRateLimiterFeedback153niks3-go-unit-tests> === RUN TestRateLimiterFeedback/429_enables_limiter154niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter155niks3-go-unit-tests> === RUN TestRateLimiterFeedback/503_enables_limiter156niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter157niks3-go-unit-tests> === RUN TestRateLimiterFeedback/200_does_not_enable_limiter158niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter159niks3-go-unit-tests> === RUN TestRateLimiterFeedback/400_does_not_enable_limiter160niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter161niks3-go-unit-tests> === CONT TestClientSignaturesByStorePath162niks3-go-unit-tests> --- PASS: TestClientSignaturesByStorePath (0.00s)163niks3-go-unit-tests> === CONT TestSetClientTLS164niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)165niks3-go-unit-tests> === CONT TestStreamPushReportsSignatures166niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter enabled after throttle name=server-test rate=5167niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:54771168niks3-go-unit-tests> 2026/10/01 09:51:58 ERROR Upload failed error=boom count=1169niks3-go-unit-tests> --- PASS: TestStreamPushReportsSignatures (0.00s)170niks3-go-unit-tests> === CONT TestPathInfoHashCompatibility171niks3-go-unit-tests> === RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)172niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)173niks3-go-unit-tests> === RUN TestPathInfoHashCompatibility/old_string_format_with_colon174niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)175niks3-go-unit-tests> === CONT TestParsePathInfoJSONMultiplePaths176niks3-go-unit-tests> === RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths177niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths178niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter backed off name=server-test rate=5179niks3-go-unit-tests> === RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths180niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:54771181niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths182niks3-go-unit-tests> === CONT TestParsePathInfoJSON183niks3-go-unit-tests> === RUN TestParsePathInfoJSON/Nix_format184niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format185niks3-go-unit-tests> === RUN TestParsePathInfoJSON/Lix_format186niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format187niks3-go-unit-tests> === RUN TestParsePathInfoJSON/empty_input188niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input189niks3-go-unit-tests> === RUN TestParsePathInfoJSON/whitespace_only190niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only191niks3-go-unit-tests> === RUN TestParsePathInfoJSON/invalid_JSON192niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon193niks3-go-unit-tests> === RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI194niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI195niks3-go-unit-tests> === RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512196niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512197niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON198niks3-go-unit-tests> === CONT TestGetStorePathHash199niks3-go-unit-tests> === RUN TestGetStorePathHash/valid_store_path200niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path201niks3-go-unit-tests> === CONT TestStreamPushBatchesUnderLoad202niks3-go-unit-tests> === RUN TestGetStorePathHash/basename_without_hyphen_should_error203niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)204niks3-go-unit-tests> === CONT TestConvertHashToNix32205niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error206niks3-go-unit-tests> === RUN TestConvertHashToNix32/SRI_format_to_Nix32207niks3-go-unit-tests> === RUN TestGetStorePathHash/hash_with_invalid_characters_should_error208niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error209niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32210niks3-go-unit-tests> === RUN TestGetStorePathHash/hash_with_wrong_length_should_error211niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error212niks3-go-unit-tests> === CONT TestStreamPushReportsEveryPath213niks3-go-unit-tests> === RUN TestConvertHashToNix32/already_Nix32_format214niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format215niks3-go-unit-tests> === RUN TestConvertHashToNix32/invalid_format216niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format217niks3-go-unit-tests> === CONT TestShellSplitErrors218niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)219niks3-go-unit-tests> === CONT TestEncodeNixBase32WithRealHash220niks3-go-unit-tests> --- PASS: TestStreamPushReportsEveryPath (0.00s)221niks3-go-unit-tests> === CONT TestDumpPathWriterError222niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)223niks3-go-unit-tests> === CONT TestEncodeNixBase32224niks3-go-unit-tests> === RUN TestEncodeNixBase32/test_string_hash225niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash226niks3-go-unit-tests> === RUN TestEncodeNixBase32/empty_input227niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input228niks3-go-unit-tests> === CONT TestUploadMultipart_SupersededByPeer229niks3-go-unit-tests> === RUN TestUploadMultipart_SupersededByPeer/exists230niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists231niks3-go-unit-tests> === RUN TestUploadMultipart_SupersededByPeer/missing232niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing233niks3-go-unit-tests> === CONT TestDumpPathSingleFile234niks3-go-unit-tests> === RUN TestSetClientTLS/rejects_connection_without_client_cert235niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert236niks3-go-unit-tests> === RUN TestSetClientTLS/succeeds_with_client_cert_and_CA237niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA238niks3-go-unit-tests> === RUN TestSetClientTLS/preserves_debug_logging_transport239niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport240niks3-go-unit-tests> === CONT TestDumpPathMatchesNix241niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.02s)242niks3-go-unit-tests> === CONT TestFilterOversizedClosures243niks3-go-unit-tests> === RUN TestFilterOversizedClosures/no_limit_keeps_everything244niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/no_limit_keeps_everything245niks3-go-unit-tests> === RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped246niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped247niks3-go-unit-tests> === RUN TestFilterOversizedClosures/all_closures_skipped248niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/all_closures_skipped249niks3-go-unit-tests> === CONT TestPartSizeForNAR250niks3-go-unit-tests> === RUN TestPartSizeForNAR/zero_stays_at_minimum251niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum252niks3-go-unit-tests> === RUN TestPartSizeForNAR/small_stays_at_minimum253niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum254niks3-go-unit-tests> === RUN TestPartSizeForNAR/80_GiB_fits_at_minimum255niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum256niks3-go-unit-tests> === RUN TestPartSizeForNAR/115_GiB_needs_larger_parts257niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts258niks3-go-unit-tests> === RUN TestPartSizeForNAR/1_TiB259niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB260niks3-go-unit-tests> === RUN TestPartSizeForNAR/5_TiB_S3_max_object261niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object262niks3-go-unit-tests> === RUN TestPartSizeForNAR/capped_at_5_GiB263niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB264niks3-go-unit-tests> === CONT TestUploadMultipart_PartsInParallel265niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)266niks3-go-unit-tests> === CONT TestCaseHackSuffix267niks3-go-unit-tests> --- PASS: TestStreamPushRequestLine (0.03s)268niks3-go-unit-tests> === CONT TestRegisterUploadedObjectReusesConnections269niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.05s)270niks3-go-unit-tests> === CONT TestSetClientTLSErrors/missing_cert_file271niks3-go-unit-tests> === CONT TestSetClientTLSErrors/missing_ca_file272niks3-go-unit-tests> === CONT TestSetClientTLSErrors/invalid_ca_file273niks3-go-unit-tests> === CONT TestSetClientTLSErrors/missing_key_file274niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)275niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)276niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)277niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)278niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)279niks3-go-unit-tests> === CONT TestPathInfoCACompatibility/null_ca_field280niks3-go-unit-tests> === CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method281niks3-go-unit-tests> === CONT TestPathInfoCACompatibility/new_structured_format_-_text282niks3-go-unit-tests> === CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive283niks3-go-unit-tests> === CONT TestPathInfoCACompatibility/old_string_format_-_text284niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)285niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)286niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)287niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)288niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)289niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)290niks3-go-unit-tests> === CONT TestRateLimiterFeedback/429_enables_limiter291niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter enabled after throttle name=server-test rate=5292niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:54846293niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter backed off name=server-test rate=5294niks3-go-unit-tests> === CONT TestRateLimiterFeedback/200_does_not_enable_limiter295niks3-go-unit-tests> === CONT TestRateLimiterFeedback/400_does_not_enable_limiter296niks3-go-unit-tests> === CONT TestRateLimiterFeedback/503_enables_limiter297niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter enabled after throttle name=server-test rate=5298niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:54852299niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.06s)300niks3-go-unit-tests> === CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths301niks3-go-unit-tests> === CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths302niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Rate limiter backed off name=server-test rate=5303niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)304niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)305niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)306niks3-go-unit-tests> === CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)307niks3-go-unit-tests> === CONT TestParsePathInfoJSON/Nix_format308niks3-go-unit-tests> === CONT TestParsePathInfoJSON/whitespace_only309niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)310niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)311niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)312niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)313niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)314niks3-go-unit-tests> === CONT TestParsePathInfoJSON/empty_input315niks3-go-unit-tests> === CONT TestParsePathInfoJSON/Lix_format316niks3-go-unit-tests> === CONT TestParsePathInfoJSON/invalid_JSON317niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)318niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)319niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)320niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON/empty_input (0.00s)321niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)322niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)323niks3-go-unit-tests> === CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI324niks3-go-unit-tests> === CONT TestPathInfoHashCompatibility/old_string_format_with_colon325niks3-go-unit-tests> === CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512326niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)327niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)328niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)329niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)330niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)331niks3-go-unit-tests> === CONT TestGetStorePathHash/valid_store_path332niks3-go-unit-tests> === CONT TestGetStorePathHash/hash_with_wrong_length_should_error333niks3-go-unit-tests> === CONT TestGetStorePathHash/hash_with_invalid_characters_should_error334niks3-go-unit-tests> === CONT TestGetStorePathHash/basename_without_hyphen_should_error335niks3-go-unit-tests> === CONT TestConvertHashToNix32/SRI_format_to_Nix32336niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)337niks3-go-unit-tests> --- PASS: TestGetStorePathHash/valid_store_path (0.00s)338niks3-go-unit-tests> --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)339niks3-go-unit-tests> --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)340niks3-go-unit-tests> --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)341niks3-go-unit-tests> === CONT TestConvertHashToNix32/already_Nix32_format342niks3-go-unit-tests> === CONT TestConvertHashToNix32/invalid_format343niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)344niks3-go-unit-tests> --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)345niks3-go-unit-tests> --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)346niks3-go-unit-tests> --- PASS: TestConvertHashToNix32/invalid_format (0.00s)347niks3-go-unit-tests> === CONT TestEncodeNixBase32/test_string_hash348niks3-go-unit-tests> === CONT TestUploadMultipart_SupersededByPeer/exists349niks3-go-unit-tests> === CONT TestEncodeNixBase32/empty_input350niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)351niks3-go-unit-tests> --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)352niks3-go-unit-tests> --- PASS: TestEncodeNixBase32/empty_input (0.00s)353niks3-go-unit-tests> === CONT TestUploadMultipart_SupersededByPeer/missing354niks3-go-unit-tests> === CONT TestSetClientTLS/rejects_connection_without_client_cert355niks3-go-unit-tests> === CONT TestSetClientTLS/preserves_debug_logging_transport356niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)357niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)358niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)359niks3-go-unit-tests> === CONT TestSetClientTLS/succeeds_with_client_cert_and_CA360niks3-go-unit-tests> === CONT TestFilterOversizedClosures/no_limit_keeps_everything361niks3-go-unit-tests> === CONT TestFilterOversizedClosures/all_closures_skipped362niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper nar_size=100 max_nar_size=50363niks3-go-unit-tests> === CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped364niks3-go-unit-tests> 2026/10/01 09:51:58 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb-vm-image nar_size=5000 max_nar_size=2000365niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures (0.00s)366niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)367niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)368niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)369niks3-go-unit-tests> === CONT TestPartSizeForNAR/zero_stays_at_minimum370niks3-go-unit-tests> === CONT TestPartSizeForNAR/1_TiB371niks3-go-unit-tests> === CONT TestPartSizeForNAR/capped_at_5_GiB372niks3-go-unit-tests> === CONT TestPartSizeForNAR/5_TiB_S3_max_object373niks3-go-unit-tests> === CONT TestPartSizeForNAR/80_GiB_fits_at_minimum374niks3-go-unit-tests> === CONT TestPartSizeForNAR/115_GiB_needs_larger_parts375niks3-go-unit-tests> === CONT TestPartSizeForNAR/small_stays_at_minimum376niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)377niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)378niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/1_TiB (0.00s)379niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)380niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)381niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)382niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)383niks3-go-unit-tests> --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)384niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.06s)385niks3-go-unit-tests> --- PASS: TestRegisterUploadedObjectReusesConnections (0.04s)386niks3-go-unit-tests> 2026/10/01 09:51:58 http: TLS handshake error from 127.0.0.1:54858: remote error: tls: bad certificate387niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.00s)388niks3-go-unit-tests> --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)389niks3-go-unit-tests> --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)390niks3-go-unit-tests> --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)391niks3-go-unit-tests> --- PASS: TestStreamPushBatchesUnderLoad (0.11s)392niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.25s)393niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.26s)394niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.30s)395niks3-go-unit-tests> --- PASS: TestUploadMultipart_PartsInParallel (0.64s)396niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)397niks3-go-unit-tests> PASS398niks3-go-unit-tests> Running server tests...399niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".400niks3-go-unit-tests> This user must also own the server process.401niks3-go-unit-tests> 402niks3-go-unit-tests> The database cluster will be initialized with locale "C".403niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".404niks3-go-unit-tests> The default text search configuration will be set to "english".405niks3-go-unit-tests> 406niks3-go-unit-tests> Data page checksums are enabled.407niks3-go-unit-tests> 408niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-89420-1799472875/postgres4152228415/data ... ok409niks3-go-unit-tests> creating subdirectories ... ok410niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix411niks3-go-unit-tests> selecting default "max_connections" ... 100412niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB413niks3-go-unit-tests> selecting default time zone ... UTC414niks3-go-unit-tests> creating configuration files ... ok415niks3-go-unit-tests> running bootstrap script ... ok416niks3-go-unit-tests> performing post-bootstrap initialization ... ok417niks3-go-unit-tests> syncing data to disk ... ok418niks3-go-unit-tests> 419niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections420niks3-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.421niks3-go-unit-tests> 422niks3-go-unit-tests> Success. You can now start the database server using:423niks3-go-unit-tests> 424niks3-go-unit-tests> pg_ctl -D /nix/var/nix/builds/nix-89420-1799472875/postgres4152228415/data -l logfile start425niks3-go-unit-tests> 426niks3-go-unit-tests> /nix/var/nix/builds/nix-89420-1799472875/postgres4152228415:5432 - no response427niks3-go-unit-tests> 2026-10-01 09:52:02.362 UTC [89880] LOG: starting PostgreSQL 18.6 on aarch64-apple-darwin25.6.0, compiled by clang version 21.1.8, 64-bit428niks3-go-unit-tests> 2026-10-01 09:52:02.362 UTC [89880] LOG: listening on Unix socket "/nix/var/nix/builds/nix-89420-1799472875/postgres4152228415/.s.PGSQL.5432"429niks3-go-unit-tests> 2026-10-01 09:52:02.369 UTC [89887] LOG: database system was shut down at 2026-10-01 09:52:02 UTC430niks3-go-unit-tests> 2026-10-01 09:52:02.371 UTC [89880] LOG: database system is ready to accept connections431niks3-go-unit-tests> /nix/var/nix/builds/nix-89420-1799472875/postgres4152228415:5432 - accepting connections432niks3-go-unit-tests> {"timestamp":"2026-10-01T09:52:02.593795Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"7108caef-4145-4433-a880-afa95dcfdced","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}433niks3-go-unit-tests> {"timestamp":"2026-10-01T09:52:02.696466Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"63cdd3e0-37be-4b6e-9570-9226dc8ae3d6","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}434niks3-go-unit-tests> === RUN TestService_AuthMiddleware435niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware436niks3-go-unit-tests> === RUN TestService_AuthMiddleware_MTLSProxyHeader437niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader438niks3-go-unit-tests> === RUN TestService_AuthMiddleware_MTLSBoundSubjects439niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects440niks3-go-unit-tests> === RUN TestService_ReadAuthMiddleware441niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware442niks3-go-unit-tests> === RUN TestService_AuthMiddleware_OIDC443niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC444niks3-go-unit-tests> === RUN TestService_RequireScope_OIDC445niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC446niks3-go-unit-tests> === RUN TestService_ReadScope_PublicByDefault447niks3-go-unit-tests> === PAUSE TestService_ReadScope_PublicByDefault448niks3-go-unit-tests> === RUN TestCacheConfigHandler449niks3-go-unit-tests> === PAUSE TestCacheConfigHandler450niks3-go-unit-tests> === RUN TestCacheStatsHandler451niks3-go-unit-tests> === PAUSE TestCacheStatsHandler452niks3-go-unit-tests> === RUN TestClientCADerivations453niks3-go-unit-tests> === PAUSE TestClientCADerivations454niks3-go-unit-tests> === RUN TestClientErrorHandling455niks3-go-unit-tests> === PAUSE TestClientErrorHandling456niks3-go-unit-tests> === RUN TestClientIntegration457niks3-go-unit-tests> === PAUSE TestClientIntegration458niks3-go-unit-tests> === RUN TestClientMultipleUploads459niks3-go-unit-tests> === PAUSE TestClientMultipleUploads460niks3-go-unit-tests> === RUN TestClientWithDependencies461niks3-go-unit-tests> === PAUSE TestClientWithDependencies462niks3-go-unit-tests> === RUN TestClientSharedPathCommittedMidPush463niks3-go-unit-tests> === PAUSE TestClientSharedPathCommittedMidPush464niks3-go-unit-tests> === RUN TestPinProtectsFromGC465niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC466niks3-go-unit-tests> === RUN TestClientPushesUseOnePush467niks3-go-unit-tests> === PAUSE TestClientPushesUseOnePush468niks3-go-unit-tests> === RUN TestClientFallsBackToClosures469niks3-go-unit-tests> === PAUSE TestClientFallsBackToClosures470niks3-go-unit-tests> === RUN TestConcurrentCommitsSharingObjectsDoNotDeadlock471niks3-go-unit-tests> === PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock472niks3-go-unit-tests> === RUN TestResolveDBConnectionString473niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString474niks3-go-unit-tests> === RUN TestLeadElectsOneAndHandsOver475niks3-go-unit-tests> === PAUSE TestLeadElectsOneAndHandsOver476niks3-go-unit-tests> === RUN TestLeadIncumbentWinsAfterRestart477niks3-go-unit-tests> 2026-10-01 09:52:03.000 UTC [89923] ERROR: relation "goose_db_version" does not exist at character 36478niks3-go-unit-tests> 2026-10-01 09:52:03.000 UTC [89923] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC479niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20241026095416_initial_model.sql (13.66ms)480niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20251210153512_drop_unused_gin_index.sql (1.55ms)481niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20251218171726_add_pins.sql (3.04ms)482niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260628120000_add_object_size_and_stats.sql (2.47ms)483niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260905000000_add_claims.sql (2.88ms)484niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260920000000_drop_claims.sql (1.41ms)485niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260923120000_add_pushes.sql (1.24ms)486niks3-go-unit-tests> 2026/10/01 09:52:03 goose: successfully migrated database to version: 20260923120000487niks3-go-unit-tests> 2026/10/01 09:52:03 OK 1_commit_pending_closure.sql (2.06ms)488niks3-go-unit-tests> 2026/10/01 09:52:03 OK 2_object_stats_trigger.sql (593.46µs)489niks3-go-unit-tests> 2026/10/01 09:52:03 OK 3_commit_push.sql (447.29µs)490niks3-go-unit-tests> 2026/10/01 09:52:03 goose: up to current file version: 3491niks3-go-unit-tests> 2026/10/01 09:52:03 INFO lead: acquired remote=192.0.2.1:1234492niks3-go-unit-tests> 2026/10/01 09:52:03 INFO lead: released remote=192.0.2.1:1234493niks3-go-unit-tests> 2026/10/01 09:52:03 INFO lead: acquired remote=192.0.2.1:1234494niks3-go-unit-tests> 2026/10/01 09:52:03 INFO lead: released remote=192.0.2.1:1234495niks3-go-unit-tests> --- PASS: TestLeadIncumbentWinsAfterRestart (1.01s)496niks3-go-unit-tests> === RUN TestLeadEndsOnShutdown497niks3-go-unit-tests> === PAUSE TestLeadEndsOnShutdown498niks3-go-unit-tests> === RUN TestGCAdvisoryLockBlocksConcurrentRun499niks3-go-unit-tests> 2026-10-01 09:52:03.909 UTC [89944] ERROR: relation "goose_db_version" does not exist at character 36500niks3-go-unit-tests> 2026-10-01 09:52:03.909 UTC [89944] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC501niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20241026095416_initial_model.sql (12.43ms)502niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20251210153512_drop_unused_gin_index.sql (1.12ms)503niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20251218171726_add_pins.sql (2.07ms)504niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260628120000_add_object_size_and_stats.sql (2.3ms)505niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260905000000_add_claims.sql (2.94ms)506niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260920000000_drop_claims.sql (1.42ms)507niks3-go-unit-tests> 2026/10/01 09:52:03 OK 20260923120000_add_pushes.sql (1.18ms)508niks3-go-unit-tests> 2026/10/01 09:52:03 goose: successfully migrated database to version: 20260923120000509niks3-go-unit-tests> 2026/10/01 09:52:03 OK 1_commit_pending_closure.sql (1.9ms)510niks3-go-unit-tests> 2026/10/01 09:52:03 OK 2_object_stats_trigger.sql (1.18ms)511niks3-go-unit-tests> 2026/10/01 09:52:03 OK 3_commit_push.sql (464.75µs)512niks3-go-unit-tests> 2026/10/01 09:52:03 goose: up to current file version: 3513niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.29s)514niks3-go-unit-tests> === RUN TestGCBugBareHashReferences515niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences516niks3-go-unit-tests> === RUN TestGCMetrics517niks3-go-unit-tests> === PAUSE TestGCMetrics518niks3-go-unit-tests> === RUN TestPushDedupSurvivesConcurrentGC519niks3-go-unit-tests> === PAUSE TestPushDedupSurvivesConcurrentGC520niks3-go-unit-tests> === RUN TestDeduplicatedObjectsRecordedAsPending521niks3-go-unit-tests> === PAUSE TestDeduplicatedObjectsRecordedAsPending522niks3-go-unit-tests> === RUN TestGCSweepSkipsPendingObjects523niks3-go-unit-tests> === PAUSE TestGCSweepSkipsPendingObjects524niks3-go-unit-tests> === RUN TestTombstonedObjectOfferedWithoutWaiting525niks3-go-unit-tests> === PAUSE TestTombstonedObjectOfferedWithoutWaiting526niks3-go-unit-tests> === RUN TestGCSweepDeliversEachKeyOnce527niks3-go-unit-tests> === PAUSE TestGCSweepDeliversEachKeyOnce528niks3-go-unit-tests> === RUN TestCreatePendingClosureVerifyS3FailureReleasesConnection529niks3-go-unit-tests> === PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection530niks3-go-unit-tests> === RUN TestForceGCDuringPushOffersSweptObject531niks3-go-unit-tests> === PAUSE TestForceGCDuringPushOffersSweptObject532niks3-go-unit-tests> === RUN TestFailedS3DeleteKeepsTombstone533niks3-go-unit-tests> === PAUSE TestFailedS3DeleteKeepsTombstone534niks3-go-unit-tests> === RUN TestSweepSkipsRowsLockedByPush535niks3-go-unit-tests> === PAUSE TestSweepSkipsRowsLockedByPush536niks3-go-unit-tests> === RUN TestRegisterCompletedObjectNeedsPendingRow537niks3-go-unit-tests> === PAUSE TestRegisterCompletedObjectNeedsPendingRow538niks3-go-unit-tests> === RUN TestGetExistingObjectsLocksRows539niks3-go-unit-tests> === PAUSE TestGetExistingObjectsLocksRows540niks3-go-unit-tests> === RUN TestSweepRechecksPendingAfterLocking541niks3-go-unit-tests> === PAUSE TestSweepRechecksPendingAfterLocking542niks3-go-unit-tests> === RUN TestPushWaitsForSweepHoldingRow543niks3-go-unit-tests> === PAUSE TestPushWaitsForSweepHoldingRow544niks3-go-unit-tests> === RUN TestCommitRacingPendingCleanupKeepsObjects545niks3-go-unit-tests> === PAUSE TestCommitRacingPendingCleanupKeepsObjects546niks3-go-unit-tests> === RUN TestGCEndsOnShutdown547niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown548niks3-go-unit-tests> === RUN TestGCTaskStore_StartNew549niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew550niks3-go-unit-tests> === RUN TestGCTaskStore_DeduplicateSameParams551niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams552niks3-go-unit-tests> === RUN TestGCTaskStore_ConflictDifferentParams553niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams554niks3-go-unit-tests> === RUN TestGCTaskStore_GetEmpty555niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty556niks3-go-unit-tests> === RUN TestGCTaskStore_GetReturnsLatest557niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest558niks3-go-unit-tests> === RUN TestGCTaskStore_CompletedAllowsNewTask559niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask560niks3-go-unit-tests> === RUN TestGCTaskStore_PhaseUpdates561niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates562niks3-go-unit-tests> === RUN TestGCTaskStore_Fail563niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail564niks3-go-unit-tests> === RUN TestGracefulShutdownDrainsInflight565niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight566niks3-go-unit-tests> === RUN TestService_healthCheckHandler567niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler568niks3-go-unit-tests> === RUN TestService_readinessHandler569niks3-go-unit-tests> === PAUSE TestService_readinessHandler570niks3-go-unit-tests> === RUN TestGenerateLandingPage571niks3-go-unit-tests> === PAUSE TestGenerateLandingPage572niks3-go-unit-tests> === RUN TestCacheConfigHandlerMaxNarSize573niks3-go-unit-tests> === PAUSE TestCacheConfigHandlerMaxNarSize574niks3-go-unit-tests> === RUN TestCreatePendingClosureRejectsOversizedNAR575niks3-go-unit-tests> === PAUSE TestCreatePendingClosureRejectsOversizedNAR576niks3-go-unit-tests> === RUN TestNARDeduplicationMetadataUploadBug577niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug578niks3-go-unit-tests> === RUN TestMetricsInventory579niks3-go-unit-tests> === PAUSE TestMetricsInventory580niks3-go-unit-tests> === RUN TestService_NativeMTLS581niks3-go-unit-tests> === PAUSE TestService_NativeMTLS582niks3-go-unit-tests> === RUN TestServerTLSConfig583niks3-go-unit-tests> === PAUSE TestServerTLSConfig584niks3-go-unit-tests> === RUN TestMultipartCleanup585niks3-go-unit-tests> === PAUSE TestMultipartCleanup586niks3-go-unit-tests> === RUN TestMultipartUploadAbortedWhenCancelledBeforeRecorded587niks3-go-unit-tests> === PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded588niks3-go-unit-tests> === RUN TestPendingCleanupUsesOneCutoff589niks3-go-unit-tests> === PAUSE TestPendingCleanupUsesOneCutoff590niks3-go-unit-tests> === RUN TestObjectStatsTrigger591niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger592niks3-go-unit-tests> === RUN TestOrphanedObjectsGC593niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC594niks3-go-unit-tests> === RUN TestOrphanedObjectsGCStressTest595niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest596niks3-go-unit-tests> === RUN TestCreatePin_ReservedPins597niks3-go-unit-tests> === PAUSE TestCreatePin_ReservedPins598niks3-go-unit-tests> === RUN TestPresentReportsOnlyClosureRoots599niks3-go-unit-tests> === PAUSE TestPresentReportsOnlyClosureRoots600niks3-go-unit-tests> === RUN TestPresentNotReportedWhileGCDeletesClosure601niks3-go-unit-tests> === PAUSE TestPresentNotReportedWhileGCDeletesClosure602niks3-go-unit-tests> === RUN TestParseSingleRange603niks3-go-unit-tests> === PAUSE TestParseSingleRange604niks3-go-unit-tests> === RUN TestProxyHeadersOnlyTrustedOnSocket605niks3-go-unit-tests> === PAUSE TestProxyHeadersOnlyTrustedOnSocket606niks3-go-unit-tests> === RUN TestIsValidCachePath607niks3-go-unit-tests> === PAUSE TestIsValidCachePath608niks3-go-unit-tests> === RUN TestReadProxyNarinfo609niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo610niks3-go-unit-tests> === RUN TestReadProxyNarinfoAlreadyDecompressed611niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed612niks3-go-unit-tests> === RUN TestReadProxyNarStreaming613niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming614niks3-go-unit-tests> === RUN TestReadProxy404615niks3-go-unit-tests> === PAUSE TestReadProxy404616niks3-go-unit-tests> === RUN TestReadProxyInvalidPath617niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath618niks3-go-unit-tests> === RUN TestReadProxyHead619niks3-go-unit-tests> === PAUSE TestReadProxyHead620niks3-go-unit-tests> === RUN TestReadProxyConditionalGet621niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet622niks3-go-unit-tests> === RUN TestReadProxyRootRedirectsToIndexHTML623niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML624niks3-go-unit-tests> === RUN TestReadProxyDisabled625niks3-go-unit-tests> === PAUSE TestReadProxyDisabled626niks3-go-unit-tests> === RUN TestReadRedirectNar627niks3-go-unit-tests> === PAUSE TestReadRedirectNar628niks3-go-unit-tests> === RUN TestReadRedirectKeepsNarinfoProxied629niks3-go-unit-tests> === PAUSE TestReadRedirectKeepsNarinfoProxied630niks3-go-unit-tests> === RUN TestReadProxyRangeRequest631niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest632niks3-go-unit-tests> === RUN TestReadRedirectUsesPublicS3URL633niks3-go-unit-tests> === PAUSE TestReadRedirectUsesPublicS3URL634niks3-go-unit-tests> === RUN TestPush_OverlappingRootsStoreOneRowPerKey635niks3-go-unit-tests> === PAUSE TestPush_OverlappingRootsStoreOneRowPerKey636niks3-go-unit-tests> === RUN TestPush_CompleteCommitsEveryRoot637niks3-go-unit-tests> === PAUSE TestPush_CompleteCommitsEveryRoot638niks3-go-unit-tests> === RUN TestPush_SkippedKeySurvivesGCBeforeCommit639niks3-go-unit-tests> === PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit640niks3-go-unit-tests> === RUN TestPush_RejectsBadRequests641niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests642niks3-go-unit-tests> === RUN TestPush_SignsNarinfosOfItsPendingObjects643niks3-go-unit-tests> === PAUSE TestPush_SignsNarinfosOfItsPendingObjects644niks3-go-unit-tests> === RUN TestRedundantMultipartUpload645niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload646niks3-go-unit-tests> === RUN TestCompleteMultipartUpload_ErrorButObjectExists647niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists648niks3-go-unit-tests> === RUN TestCompletedNarNotReofferedAcrossClosures649niks3-go-unit-tests> === PAUSE TestCompletedNarNotReofferedAcrossClosures650niks3-go-unit-tests> === RUN TestPresignedUploadRegisteredBeforeCommit651niks3-go-unit-tests> === PAUSE TestPresignedUploadRegisteredBeforeCommit652niks3-go-unit-tests> === RUN TestService_Rustfstest653niks3-go-unit-tests> === PAUSE TestService_Rustfstest654niks3-go-unit-tests> === RUN TestParseSize655niks3-go-unit-tests> === PAUSE TestParseSize656niks3-go-unit-tests> === RUN TestSkippedUploadsHandler657niks3-go-unit-tests> === PAUSE TestSkippedUploadsHandler658niks3-go-unit-tests> === RUN TestSystemdListenerNotActivated659niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)660niks3-go-unit-tests> === RUN TestWatchdogBeatsWhenHealthy661niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.03s)662niks3-go-unit-tests> === RUN TestWatchdogSkipsWhenUnhealthy663niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"664niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"665niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"666niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"667niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"668niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"669niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"670niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"671niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"672niks3-go-unit-tests> 2026/10/01 09:52:04 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"673niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.21s)674niks3-go-unit-tests> === RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle675niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle676niks3-go-unit-tests> === RUN TestProxyWriteTimeout677niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout678niks3-go-unit-tests> === RUN TestIsValidUploadKey679niks3-go-unit-tests> === PAUSE TestIsValidUploadKey680niks3-go-unit-tests> === RUN TestUploadHandlersRejectInvalidKeys681niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys682niks3-go-unit-tests> === RUN TestUploadHandlersRejectOversizedBody683niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody684niks3-go-unit-tests> === RUN TestService_cleanupPendingClosuresHandler685niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler686niks3-go-unit-tests> === RUN TestService_createPendingClosureHandler687niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler688niks3-go-unit-tests> === RUN TestService_verifyS3Integrity689niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity690niks3-go-unit-tests> === RUN TestCompleteMultipartUnregistered691niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered692niks3-go-unit-tests> === RUN TestCreatePendingClosure_SmallNARUsesSimplePUT693niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT694niks3-go-unit-tests> === CONT TestService_AuthMiddleware695niks3-go-unit-tests> === CONT TestService_verifyS3Integrity696niks3-go-unit-tests> === CONT TestService_createPendingClosureHandler697niks3-go-unit-tests> === CONT TestGenerateLandingPage698niks3-go-unit-tests> === CONT TestReadProxyConditionalGet699niks3-go-unit-tests> === CONT TestDeduplicatedObjectsRecordedAsPending700niks3-go-unit-tests> === CONT TestCreatePendingClosure_SmallNARUsesSimplePUT701niks3-go-unit-tests> === CONT TestClientWithDependencies702niks3-go-unit-tests> === CONT TestService_cleanupPendingClosuresHandler703niks3-go-unit-tests> === CONT TestCompleteMultipartUnregistered704niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)705niks3-go-unit-tests> === CONT TestUploadHandlersRejectOversizedBody706niks3-go-unit-tests> === RUN TestUploadHandlersRejectOversizedBody/request_more_parts707niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts708niks3-go-unit-tests> === RUN TestUploadHandlersRejectOversizedBody/create_pending_closure709niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure710niks3-go-unit-tests> === RUN TestUploadHandlersRejectOversizedBody/complete_multipart711niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart712niks3-go-unit-tests> === CONT TestUploadHandlersRejectInvalidKeys713niks3-go-unit-tests> === RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info714niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info715niks3-go-unit-tests> === RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal716niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal717niks3-go-unit-tests> === RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key718niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key719niks3-go-unit-tests> === RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key720niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key721niks3-go-unit-tests> === CONT TestIsValidUploadKey722niks3-go-unit-tests> === RUN TestIsValidUploadKey/narinfo723niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo724niks3-go-unit-tests> === RUN TestIsValidUploadKey/nar_zst725niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst726niks3-go-unit-tests> === RUN TestIsValidUploadKey/nar_xz727niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz728niks3-go-unit-tests> === RUN TestIsValidUploadKey/nar_plain729niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain730niks3-go-unit-tests> === RUN TestIsValidUploadKey/listing731niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing732niks3-go-unit-tests> === RUN TestIsValidUploadKey/build_log733niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log734niks3-go-unit-tests> === RUN TestIsValidUploadKey/build_log_home-manager_file735niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file736niks3-go-unit-tests> === RUN TestIsValidUploadKey/build_log_plus_in_name737niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name738niks3-go-unit-tests> === RUN TestIsValidUploadKey/build_log_question_mark739niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark740niks3-go-unit-tests> === RUN TestIsValidUploadKey/build_log_equals741niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals742niks3-go-unit-tests> === RUN TestIsValidUploadKey/realisation743niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation744niks3-go-unit-tests> === RUN TestIsValidUploadKey/realisation_plus_in_output745niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output746niks3-go-unit-tests> === RUN TestIsValidUploadKey/nix-cache-info747niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info748niks3-go-unit-tests> === RUN TestIsValidUploadKey/index.html749niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html750niks3-go-unit-tests> === RUN TestIsValidUploadKey/narinfo_key,_nar_type751niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type752niks3-go-unit-tests> === RUN TestIsValidUploadKey/nar_key,_narinfo_type753niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type754niks3-go-unit-tests> === RUN TestIsValidUploadKey/listing_key,_narinfo_type755niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type756niks3-go-unit-tests> === RUN TestIsValidUploadKey/traversal757niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal758niks3-go-unit-tests> === RUN TestIsValidUploadKey/traversal_nar759niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar760niks3-go-unit-tests> === RUN TestIsValidUploadKey/absolute761niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute762niks3-go-unit-tests> === RUN TestIsValidUploadKey/empty_key763niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key764niks3-go-unit-tests> === RUN TestIsValidUploadKey/unknown_type765niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type766niks3-go-unit-tests> === CONT TestProxyWriteTimeout767niks3-go-unit-tests> === RUN TestProxyWriteTimeout/narinfo768niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo769niks3-go-unit-tests> === RUN TestProxyWriteTimeout/1_GiB_nar770niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar771niks3-go-unit-tests> === RUN TestProxyWriteTimeout/10_GiB_nar772niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar773niks3-go-unit-tests> === RUN TestProxyWriteTimeout/unknown_size774niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size775niks3-go-unit-tests> === CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle776niks3-go-unit-tests> 2026-10-01 09:52:04.581 UTC [90033] ERROR: relation "goose_db_version" does not exist at character 36777niks3-go-unit-tests> 2026-10-01 09:52:04.581 UTC [90033] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC778niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (23.2ms)779niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (2.47ms)780niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (5.58ms)781niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (7.35ms)782niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260905000000_add_claims.sql (7.2ms)783niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260920000000_drop_claims.sql (4.46ms)784niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260923120000_add_pushes.sql (2.71ms)785niks3-go-unit-tests> 2026/10/01 09:52:04 goose: successfully migrated database to version: 20260923120000786niks3-go-unit-tests> 2026/10/01 09:52:04 OK 1_commit_pending_closure.sql (3.73ms)787niks3-go-unit-tests> 2026/10/01 09:52:04 OK 2_object_stats_trigger.sql (1.75ms)788niks3-go-unit-tests> 2026/10/01 09:52:04 OK 3_commit_push.sql (1.59ms)789niks3-go-unit-tests> 2026/10/01 09:52:04 goose: up to current file version: 3790niks3-go-unit-tests> 2026-10-01 09:52:04.663 UTC [90036] ERROR: relation "goose_db_version" does not exist at character 36791niks3-go-unit-tests> 2026-10-01 09:52:04.663 UTC [90036] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC792niks3-go-unit-tests> 2026-10-01 09:52:04.675 UTC [90037] ERROR: relation "goose_db_version" does not exist at character 36793niks3-go-unit-tests> 2026-10-01 09:52:04.675 UTC [90037] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC794niks3-go-unit-tests> 2026-10-01 09:52:04.684 UTC [90038] ERROR: relation "goose_db_version" does not exist at character 36795niks3-go-unit-tests> 2026-10-01 09:52:04.684 UTC [90038] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC796niks3-go-unit-tests> 2026-10-01 09:52:04.690 UTC [90039] ERROR: relation "goose_db_version" does not exist at character 36797niks3-go-unit-tests> 2026-10-01 09:52:04.690 UTC [90039] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC798niks3-go-unit-tests> 2026-10-01 09:52:04.711 UTC [90040] ERROR: relation "goose_db_version" does not exist at character 36799niks3-go-unit-tests> 2026-10-01 09:52:04.711 UTC [90040] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC800niks3-go-unit-tests> 2026-10-01 09:52:04.712 UTC [90041] ERROR: relation "goose_db_version" does not exist at character 36801niks3-go-unit-tests> 2026-10-01 09:52:04.712 UTC [90041] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC802niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (72.34ms)803niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (12.18ms)804niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (17.91ms)805niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (85.42ms)806niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (8.67ms)807niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (32.31ms)808niks3-go-unit-tests> 2026-10-01 09:52:04.829 UTC [90042] ERROR: relation "goose_db_version" does not exist at character 36809niks3-go-unit-tests> 2026-10-01 09:52:04.829 UTC [90042] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC810niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (119.49ms)811niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260905000000_add_claims.sql (12.47ms)812niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (120.35ms)813niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (29.14ms)814niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (95.09ms)815niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20241026095416_initial_model.sql (96.43ms)816niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (4.5ms)817niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (6.81ms)818niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (6.35ms)819niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251210153512_drop_unused_gin_index.sql (11.01ms)820niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260920000000_drop_claims.sql (30.21ms)821niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (29.03ms)822niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260923120000_add_pushes.sql (8.5ms)823niks3-go-unit-tests> 2026/10/01 09:52:04 goose: successfully migrated database to version: 20260923120000824niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (31.75ms)825niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (33.62ms)826niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (33.7ms)827niks3-go-unit-tests> 2026/10/01 09:52:04 OK 1_commit_pending_closure.sql (2.17ms)828niks3-go-unit-tests> 2026/10/01 09:52:04 OK 2_object_stats_trigger.sql (569.33µs)829niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.54s)830niks3-go-unit-tests> === CONT TestGCEndsOnShutdown831niks3-go-unit-tests> 2026/10/01 09:52:04 OK 3_commit_push.sql (544.92µs)832niks3-go-unit-tests> 2026/10/01 09:52:04 goose: up to current file version: 3833niks3-go-unit-tests> 2026-10-01 09:52:04.880 UTC [90043] ERROR: relation "goose_db_version" does not exist at character 36834niks3-go-unit-tests> 2026-10-01 09:52:04.880 UTC [90043] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC835niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20251218171726_add_pins.sql (49.7ms)836niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (45.44ms)837niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (48.04ms)838niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (61.01ms)839niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260628120000_add_object_size_and_stats.sql (37.25ms)840niks3-go-unit-tests> 2026-10-01 09:52:04.960 UTC [90044] ERROR: relation "goose_db_version" does not exist at character 36841niks3-go-unit-tests> 2026-10-01 09:52:04.960 UTC [90044] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC842niks3-go-unit-tests> 2026/10/01 09:52:04 OK 20260905000000_add_claims.sql (103.64ms)843niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (80.88ms)844niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (68.15ms)845niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (14.82ms)846niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (49.96ms)847niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (101.5ms)848niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (87.68ms)849niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20241026095416_initial_model.sql (138.21ms)850niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (10.91ms)851niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000852niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (1.98ms)853niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (536.42µs)854niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (387.42µs)855niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3856niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251210153512_drop_unused_gin_index.sql (16.35ms)857niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (20.48ms)858niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000859niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (2.14ms)860niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (540.38µs)861niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (372.29µs)862niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3863niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (47.91ms)864niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (30.97ms)865niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (30.81ms)866niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (9.27ms)867niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000868niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (2ms)869niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (570.17µs)870niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (461.63µs)871niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3872niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251218171726_add_pins.sql (32.09ms)873niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (18.87ms)874niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000875niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (18.96ms)876niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000877niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (1.81ms)878niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (1.84ms)879niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (503.33µs)880niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (606.42µs)881niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (424.71µs)882niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3883niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (440.13µs)884niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3885niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20241026095416_initial_model.sql (122.27ms)886niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251210153512_drop_unused_gin_index.sql (13.95ms)887niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260628120000_add_object_size_and_stats.sql (30.13ms)888niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251218171726_add_pins.sql (18.22ms)889niks3-go-unit-tests> 2026/10/01 09:52:05 INFO Received uploads request method=POST path=/api/pending_closures890niks3-go-unit-tests> 2026/10/01 09:52:05 INFO Received uploads request method=POST path=/api/pending_closures891niks3-go-unit-tests> 2026/10/01 09:52:05 INFO Received uploads request method=POST path=/api/pending_closures892niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260628120000_add_object_size_and_stats.sql (28.27ms)893niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (60.81ms)894niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (34.48ms)895niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20241026095416_initial_model.sql (181.19ms)896niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (57.92ms)897niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (18.66ms)898niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000899niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251210153512_drop_unused_gin_index.sql (12ms)900niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (2.26ms)901niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (597.29µs)902niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (375.21µs)903niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3904niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (31.36ms)905niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (18.03ms)906niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000907niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (2.01ms)908niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (553.79µs)909niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (412.04µs)910niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3911niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20251218171726_add_pins.sql (43.49ms)912niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260628120000_add_object_size_and_stats.sql (24.75ms)913niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260905000000_add_claims.sql (43.43ms)914niks3-go-unit-tests> 2026/10/01 09:52:05 INFO Received uploads request method=POST path=/api/pending_closures915niks3-go-unit-tests> --- PASS: TestDeduplicatedObjectsRecordedAsPending (1.02s)916niks3-go-unit-tests> === CONT TestService_readinessHandler917niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260920000000_drop_claims.sql (58.12ms)918niks3-go-unit-tests> 2026/10/01 09:52:05 OK 20260923120000_add_pushes.sql (17.13ms)919niks3-go-unit-tests> 2026/10/01 09:52:05 goose: successfully migrated database to version: 20260923120000920niks3-go-unit-tests> 2026/10/01 09:52:05 OK 1_commit_pending_closure.sql (2.04ms)921niks3-go-unit-tests> 2026/10/01 09:52:05 OK 2_object_stats_trigger.sql (438.21µs)922niks3-go-unit-tests> 2026/10/01 09:52:05 OK 3_commit_push.sql (401.21µs)923niks3-go-unit-tests> 2026/10/01 09:52:05 goose: up to current file version: 3924niks3-go-unit-tests> 2026/10/01 09:52:05 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"925niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (1.21s)926niks3-go-unit-tests> === CONT TestSkippedUploadsHandler927niks3-go-unit-tests> 2026/10/01 09:52:05 INFO Client skipped oversized paths paths=3 nar_bytes=5000000000928niks3-go-unit-tests> --- PASS: TestSkippedUploadsHandler (0.00s)929niks3-go-unit-tests> === CONT TestService_healthCheckHandler