niks3-go-unit-tests
checks.aarch64-linux.go-unit-tests
· build #231
· raw
1tribuchet: building on eliza2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestRegisterUploadedObjectReusesConnections6=== PAUSE TestRegisterUploadedObjectReusesConnections7=== RUN TestCaseHackSuffix8=== PAUSE TestCaseHackSuffix9=== RUN TestFilterOversizedClosures10=== PAUSE TestFilterOversizedClosures11=== RUN TestPartSizeForNAR12=== PAUSE TestPartSizeForNAR13=== RUN TestUploadMultipart_SupersededByPeer14=== PAUSE TestUploadMultipart_SupersededByPeer15=== RUN TestDumpPathCaseHackMatchesNix16--- PASS: TestDumpPathCaseHackMatchesNix (0.07s)17=== RUN TestDumpPathCaseHackCollision18--- PASS: TestDumpPathCaseHackCollision (0.00s)19=== RUN TestDumpPathMatchesNix20=== PAUSE TestDumpPathMatchesNix21=== RUN TestDumpPathSingleFile22=== PAUSE TestDumpPathSingleFile23=== RUN TestDumpPathWriterError24=== PAUSE TestDumpPathWriterError25=== RUN TestEncodeNixBase3226=== PAUSE TestEncodeNixBase3227=== RUN TestEncodeNixBase32WithRealHash28=== PAUSE TestEncodeNixBase32WithRealHash29=== RUN TestConvertHashToNix3230=== PAUSE TestConvertHashToNix3231=== RUN TestGetStorePathHash32=== PAUSE TestGetStorePathHash33=== RUN TestPathInfoHashCompatibility34=== PAUSE TestPathInfoHashCompatibility35=== RUN TestParsePathInfoJSON36=== PAUSE TestParsePathInfoJSON37=== RUN TestParsePathInfoJSONMultiplePaths38=== PAUSE TestParsePathInfoJSONMultiplePaths39=== RUN TestPathInfoCACompatibility40=== PAUSE TestPathInfoCACompatibility41=== RUN TestRateLimiterFeedback42=== PAUSE TestRateLimiterFeedback43=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess44=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess45=== RUN TestResolveStorePath46=== PAUSE TestResolveStorePath47=== RUN TestDoWithRetry_BodyReplayedViaGetBody48=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody49=== RUN TestShellSplit50=== PAUSE TestShellSplit51=== RUN TestShellSplitErrors52=== PAUSE TestShellSplitErrors53=== RUN TestStreamPushReportsEveryPath54=== PAUSE TestStreamPushReportsEveryPath55=== RUN TestStreamPushBatchesUnderLoad56=== PAUSE TestStreamPushBatchesUnderLoad57=== RUN TestStreamPushIsolatesFailures58=== PAUSE TestStreamPushIsolatesFailures59=== RUN TestStreamPushGivesUpOnDeadServer60=== PAUSE TestStreamPushGivesUpOnDeadServer61=== RUN TestStreamPushRequestLine62=== PAUSE TestStreamPushRequestLine63=== RUN TestSetClientTLS64=== PAUSE TestSetClientTLS65=== RUN TestSetClientTLSDoesNotMutateDefaultTransport66=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport67=== RUN TestSetClientTLSErrors68=== PAUSE TestSetClientTLSErrors69=== RUN TestStaticToken70=== PAUSE TestStaticToken71=== RUN TestFileTokenReadsAndCaches72=== PAUSE TestFileTokenReadsAndCaches73=== RUN TestFileTokenMissing74=== PAUSE TestFileTokenMissing75=== RUN TestFileTokenEmpty76=== PAUSE TestFileTokenEmpty77=== RUN TestScriptTokenNoExpiryRerunsEveryCall78=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall79=== RUN TestScriptTokenCachesUntilRefresh80=== PAUSE TestScriptTokenCachesUntilRefresh81=== RUN TestScriptTokenEmptyToken82=== PAUSE TestScriptTokenEmptyToken83=== RUN TestScriptTokenBadJSON84=== PAUSE TestScriptTokenBadJSON85=== RUN TestScriptTokenScriptFails86=== PAUSE TestScriptTokenScriptFails87=== RUN TestScriptTokenEmptyCommand88=== PAUSE TestScriptTokenEmptyCommand89=== CONT TestDoServerRequestAttachesToken90=== CONT TestStaticToken91=== CONT TestScriptTokenCachesUntilRefresh92=== CONT TestShellSplit93=== CONT TestScriptTokenEmptyCommand94=== CONT TestFileTokenEmpty95=== CONT TestStreamPushReportsEveryPath96=== CONT TestScriptTokenScriptFails97=== CONT TestScriptTokenBadJSON98=== CONT TestScriptTokenNoExpiryRerunsEveryCall99=== CONT TestScriptTokenEmptyToken100=== CONT TestSetClientTLSDoesNotMutateDefaultTransport101=== CONT TestSetClientTLSErrors102=== CONT TestParsePathInfoJSON103=== CONT TestStreamPushGivesUpOnDeadServer104=== CONT TestStreamPushBatchesUnderLoad105=== CONT TestSetClientTLS106=== CONT TestStreamPushIsolatesFailures107=== CONT TestStreamPushRequestLine108=== CONT TestDumpPathMatchesNix109=== CONT TestPathInfoCACompatibility110=== CONT TestEncodeNixBase32WithRealHash111=== CONT TestDoWithRetry_BodyReplayedViaGetBody112=== CONT TestEncodeNixBase32113=== CONT TestResolveStorePath114=== CONT TestDumpPathWriterError115=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess116=== CONT TestDumpPathSingleFile117--- PASS: TestStaticToken (0.00s)118=== CONT TestRateLimiterFeedback119=== RUN TestParsePathInfoJSON/Nix_format120=== RUN TestPathInfoCACompatibility/null_ca_field121=== PAUSE TestPathInfoCACompatibility/null_ca_field122=== RUN TestPathInfoCACompatibility/old_string_format_-_text123=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text124=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive125=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive126=== RUN TestPathInfoCACompatibility/new_structured_format_-_text127=== CONT TestPathInfoHashCompatibility128=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text129--- PASS: TestScriptTokenEmptyCommand (0.00s)130--- PASS: TestShellSplit (0.00s)131--- PASS: TestFileTokenEmpty (0.00s)132=== CONT TestParsePathInfoJSONMultiplePaths1332026/09/21 12:56:19 ERROR Upload failed error="bad path" count=3134=== PAUSE TestParsePathInfoJSON/Nix_format135=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths136=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths137=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)1382026/09/21 12:56:19 WARN Rate limiter enabled after throttle name=server-test rate=5139=== RUN TestParsePathInfoJSON/Lix_format1402026/09/21 12:56:19 ERROR Upload failed error=boom count=11412026/09/21 12:56:19 WARN Rate limiter enabled after throttle name=server-test rate=51422026/09/21 12:56:19 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:38719143=== RUN TestRateLimiterFeedback/429_enables_limiter144=== PAUSE TestRateLimiterFeedback/429_enables_limiter145--- PASS: TestScriptTokenScriptFails (0.01s)146=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method147--- PASS: TestStreamPushReportsEveryPath (0.01s)1482026/09/21 12:56:19 ERROR Upload failed error="connection refused" count=20149=== RUN TestEncodeNixBase32/test_string_hash150=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths151=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)1522026/09/21 12:56:19 ERROR Server seems unavailable, giving up on batch untried=17153=== PAUSE TestParsePathInfoJSON/Lix_format1542026/09/21 12:56:19 WARN Rate limiter backed off name=server-test rate=5155=== CONT TestShellSplitErrors156=== RUN TestRateLimiterFeedback/503_enables_limiter1572026/09/21 12:56:19 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:38719158=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method159=== CONT TestUploadMultipart_SupersededByPeer160=== CONT TestPartSizeForNAR161=== RUN TestUploadMultipart_SupersededByPeer/exists162--- PASS: TestEncodeNixBase32WithRealHash (0.00s)163--- PASS: TestStreamPushIsolatesFailures (0.00s)164--- PASS: TestResolveStorePath (0.00s)165--- PASS: TestScriptTokenBadJSON (0.01s)166--- PASS: TestShellSplitErrors (0.00s)167--- PASS: TestScriptTokenEmptyToken (0.01s)168--- PASS: TestScriptTokenCachesUntilRefresh (0.02s)169=== RUN TestPartSizeForNAR/zero_stays_at_minimum170--- PASS: TestStreamPushGivesUpOnDeadServer (0.01s)171=== CONT TestGetStorePathHash172=== RUN TestGetStorePathHash/valid_store_path173=== CONT TestFilterOversizedClosures174=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths175=== RUN TestParsePathInfoJSON/empty_input176=== PAUSE TestRateLimiterFeedback/503_enables_limiter177=== CONT TestCaseHackSuffix178=== CONT TestFileTokenMissing179=== RUN TestSetClientTLSErrors/missing_cert_file180=== PAUSE TestEncodeNixBase32/test_string_hash181=== CONT TestRegisterUploadedObjectReusesConnections182=== PAUSE TestSetClientTLSErrors/missing_cert_file183=== RUN TestEncodeNixBase32/empty_input184=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon185=== PAUSE TestUploadMultipart_SupersededByPeer/exists186=== RUN TestFilterOversizedClosures/no_limit_keeps_everything187=== PAUSE TestParsePathInfoJSON/empty_input188=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter189=== CONT TestFileTokenReadsAndCaches190=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum191--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)192=== CONT TestConvertHashToNix32193=== RUN TestConvertHashToNix32/SRI_format_to_Nix32194=== RUN TestSetClientTLSErrors/missing_key_file195=== PAUSE TestGetStorePathHash/valid_store_path196=== PAUSE TestEncodeNixBase32/empty_input197=== PAUSE TestSetClientTLSErrors/missing_key_file198=== CONT TestPathInfoCACompatibility/null_ca_field199=== RUN TestSetClientTLSErrors/missing_ca_file200=== RUN TestParsePathInfoJSON/whitespace_only201=== RUN TestPartSizeForNAR/small_stays_at_minimum202=== PAUSE TestPartSizeForNAR/small_stays_at_minimum203=== CONT TestPathInfoCACompatibility/new_structured_format_-_text204=== PAUSE TestParsePathInfoJSON/whitespace_only205=== RUN TestParsePathInfoJSON/invalid_JSON206=== PAUSE TestParsePathInfoJSON/invalid_JSON207=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32208--- PASS: TestDoServerRequestAttachesToken (0.02s)209=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method210=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon211=== RUN TestUploadMultipart_SupersededByPeer/missing212=== RUN TestGetStorePathHash/basename_without_hyphen_should_error213=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything214=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter215=== PAUSE TestSetClientTLSErrors/missing_ca_file216=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum217--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.02s)218=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths219=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths220--- PASS: TestFileTokenReadsAndCaches (0.00s)221=== CONT TestEncodeNixBase32/test_string_hash222=== CONT TestEncodeNixBase32/empty_input223--- PASS: TestEncodeNixBase32 (0.01s)224 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)225 --- PASS: TestEncodeNixBase32/empty_input (0.00s)226=== CONT TestParsePathInfoJSON/Nix_format227=== CONT TestParsePathInfoJSON/whitespace_only228=== CONT TestParsePathInfoJSON/invalid_JSON229=== CONT TestParsePathInfoJSON/empty_input230=== CONT TestParsePathInfoJSON/Lix_format231--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)232=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error233=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter234=== CONT TestPathInfoCACompatibility/old_string_format_-_text235--- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)236 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)237 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)238--- PASS: TestFileTokenMissing (0.01s)239--- PASS: TestParsePathInfoJSON (0.02s)240 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)241 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)242 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)243 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)244 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)245=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive246=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum247=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter248=== CONT TestRateLimiterFeedback/429_enables_limiter249=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter250=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter251=== CONT TestRateLimiterFeedback/503_enables_limiter252=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI253=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI254=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped255=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped256=== RUN TestFilterOversizedClosures/all_closures_skipped257=== PAUSE TestFilterOversizedClosures/all_closures_skipped258=== CONT TestFilterOversizedClosures/no_limit_keeps_everything259=== CONT TestFilterOversizedClosures/all_closures_skipped260=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped2612026/09/21 12:56:19 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=50262=== PAUSE TestUploadMultipart_SupersededByPeer/missing263=== CONT TestUploadMultipart_SupersededByPeer/exists2642026/09/21 12:56:19 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=2000265--- PASS: TestFilterOversizedClosures (0.03s)266 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)267 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)268 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)269--- PASS: TestPathInfoCACompatibility (0.01s)270 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)271 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)272 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.02s)273 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)274 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.02s)275=== CONT TestUploadMultipart_SupersededByPeer/missing2762026/09/21 12:56:19 WARN Rate limiter enabled after throttle name=server-test rate=52772026/09/21 12:56:19 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:46651278--- PASS: TestDumpPathSingleFile (0.04s)279=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error280=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error281=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error282=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error283=== CONT TestGetStorePathHash/valid_store_path2842026/09/21 12:56:19 WARN Rate limiter backed off name=server-test rate=5285=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512286=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512287=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error288=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error289=== RUN TestSetClientTLSErrors/invalid_ca_file290=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)291=== PAUSE TestSetClientTLSErrors/invalid_ca_file292=== RUN TestSetClientTLS/rejects_connection_without_client_cert293=== CONT TestGetStorePathHash/basename_without_hyphen_should_error294=== RUN TestConvertHashToNix32/already_Nix32_format295=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512296=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI297=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts298=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon299=== CONT TestSetClientTLSErrors/missing_key_file300=== CONT TestSetClientTLSErrors/missing_ca_file3012026/09/21 12:56:19 WARN Rate limiter enabled after throttle name=server-test rate=5302=== CONT TestSetClientTLSErrors/missing_cert_file3032026/09/21 12:56:19 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:38503304=== PAUSE TestConvertHashToNix32/already_Nix32_format305=== RUN TestConvertHashToNix32/invalid_format306=== PAUSE TestConvertHashToNix32/invalid_format307=== CONT TestConvertHashToNix32/SRI_format_to_Nix32308=== CONT TestSetClientTLSErrors/invalid_ca_file309--- PASS: TestGetStorePathHash (0.03s)310 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)311 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)312 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)313 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)314=== CONT TestConvertHashToNix32/already_Nix32_format315=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts3162026/09/21 12:56:19 WARN Rate limiter backed off name=server-test rate=5317=== RUN TestPartSizeForNAR/1_TiB318=== PAUSE TestPartSizeForNAR/1_TiB319=== RUN TestPartSizeForNAR/5_TiB_S3_max_object320=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object321=== RUN TestPartSizeForNAR/capped_at_5_GiB322=== PAUSE TestPartSizeForNAR/capped_at_5_GiB323=== CONT TestConvertHashToNix32/invalid_format324=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert325--- PASS: TestPathInfoHashCompatibility (0.04s)326 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)327 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)328 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)329 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)330=== CONT TestPartSizeForNAR/zero_stays_at_minimum331=== CONT TestPartSizeForNAR/1_TiB332=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts333=== CONT TestPartSizeForNAR/capped_at_5_GiB334=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum335=== CONT TestPartSizeForNAR/5_TiB_S3_max_object336=== CONT TestPartSizeForNAR/small_stays_at_minimum337=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA338--- PASS: TestRateLimiterFeedback (0.03s)339 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)340 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)341 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)342 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)343=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA344--- PASS: TestConvertHashToNix32 (0.04s)345 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)346 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)347 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)348=== RUN TestSetClientTLS/preserves_debug_logging_transport349--- PASS: TestPartSizeForNAR (0.04s)350 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)351 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)352 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)353 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)354 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)355 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)356 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)357--- PASS: TestUploadMultipart_SupersededByPeer (0.03s)358 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)359 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)360=== PAUSE TestSetClientTLS/preserves_debug_logging_transport361=== CONT TestSetClientTLS/rejects_connection_without_client_cert362=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA363=== CONT TestSetClientTLS/preserves_debug_logging_transport364--- PASS: TestSetClientTLSErrors (0.05s)365 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)366 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)367 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)368 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)3692026/09/21 12:56:19 http: TLS handshake error from 127.0.0.1:52552: remote error: tls: bad certificate370--- PASS: TestSetClientTLS (0.05s)371 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)372 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)373 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.02s)374--- PASS: TestCaseHackSuffix (0.07s)375--- PASS: TestRegisterUploadedObjectReusesConnections (0.07s)376--- PASS: TestStreamPushRequestLine (0.08s)377--- PASS: TestDumpPathWriterError (0.09s)378--- PASS: TestStreamPushBatchesUnderLoad (0.10s)379--- PASS: TestDumpPathMatchesNix (0.14s)380--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)381PASS382Running server tests...383The files belonging to this database system will be owned by user "nixbld".384This user must also own the server process.385386The database cluster will be initialized with locale "C".387The default database encoding has accordingly been set to "SQL_ASCII".388The default text search configuration will be set to "english".389390Data page checksums are enabled.391392creating directory /build/postgres3103569926/data ... ok393creating subdirectories ... ok394selecting dynamic shared memory implementation ... posix395selecting default "max_connections" ... 100396selecting default "shared_buffers" ... 128MB397selecting default time zone ... UTC398creating configuration files ... ok399running bootstrap script ... ok400performing post-bootstrap initialization ... ok401syncing data to disk ... ok402403initdb: warning: enabling "trust" authentication for local connections404initdb: 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.405406Success. You can now start the database server using:407408 pg_ctl -D /build/postgres3103569926/data -l logfile start409410/build/postgres3103569926:5432 - no response4112026-09-21 12:56:21.180 UTC [129] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit4122026-09-21 12:56:21.182 UTC [129] LOG: listening on Unix socket "/build/postgres3103569926/.s.PGSQL.5432"4132026-09-21 12:56:21.187 UTC [136] LOG: database system was shut down at 2026-09-21 12:56:21 UTC4142026-09-21 12:56:21.190 UTC [129] LOG: database system is ready to accept connections415/build/postgres3103569926:5432 - accepting connections416=== RUN TestService_AuthMiddleware417=== PAUSE TestService_AuthMiddleware418=== RUN TestService_AuthMiddleware_MTLSProxyHeader419=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader420=== RUN TestService_AuthMiddleware_MTLSBoundSubjects421=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects422=== RUN TestService_ReadAuthMiddleware423=== PAUSE TestService_ReadAuthMiddleware424=== RUN TestService_AuthMiddleware_OIDC425=== PAUSE TestService_AuthMiddleware_OIDC426=== RUN TestService_RequireScope_OIDC427=== PAUSE TestService_RequireScope_OIDC428=== RUN TestService_ReadScope_PublicByDefault429=== PAUSE TestService_ReadScope_PublicByDefault430=== RUN TestCacheConfigHandler431=== PAUSE TestCacheConfigHandler432=== RUN TestCacheStatsHandler433=== PAUSE TestCacheStatsHandler434=== RUN TestClientCADerivations435=== PAUSE TestClientCADerivations436=== RUN TestClientErrorHandling437=== PAUSE TestClientErrorHandling438=== RUN TestClientIntegration439=== PAUSE TestClientIntegration440=== RUN TestClientMultipleUploads441=== PAUSE TestClientMultipleUploads442=== RUN TestClientWithDependencies443=== PAUSE TestClientWithDependencies444=== RUN TestClientSharedPathCommittedMidPush445=== PAUSE TestClientSharedPathCommittedMidPush446=== RUN TestPinProtectsFromGC447=== PAUSE TestPinProtectsFromGC448=== RUN TestResolveDBConnectionString449=== PAUSE TestResolveDBConnectionString450=== RUN TestLeadElectsOneAndHandsOver451=== PAUSE TestLeadElectsOneAndHandsOver452=== RUN TestLeadEndsOnShutdown453=== PAUSE TestLeadEndsOnShutdown454=== RUN TestGCAdvisoryLockBlocksConcurrentRun4552026-09-21 12:56:29.019 UTC [360] ERROR: relation "goose_db_version" does not exist at character 364562026-09-21 12:56:29.019 UTC [360] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4572026/09/21 12:56:29 OK 20241026095416_initial_model.sql (36.37ms)4582026/09/21 12:56:29 OK 20251210153512_drop_unused_gin_index.sql (5.79ms)4592026/09/21 12:56:29 OK 20251218171726_add_pins.sql (3.93ms)4602026/09/21 12:56:29 OK 20260628120000_add_object_size_and_stats.sql (3.41ms)4612026/09/21 12:56:29 OK 20260905000000_add_claims.sql (6.04ms)4622026/09/21 12:56:29 OK 20260920000000_drop_claims.sql (2.38ms)4632026/09/21 12:56:29 goose: successfully migrated database to version: 202609200000004642026/09/21 12:56:29 OK 1_commit_pending_closure.sql (1.89ms)4652026/09/21 12:56:29 OK 2_object_stats_trigger.sql (3.28ms)4662026/09/21 12:56:29 goose: up to current file version: 2467 gc_advisory_lock_test.go:17: unexpected error: Service not ready: waiting for storage_quorum468 469--- FAIL: TestGCAdvisoryLockBlocksConcurrentRun (3.14s)470=== RUN TestGCBugBareHashReferences471=== PAUSE TestGCBugBareHashReferences472=== RUN TestGCMetrics473=== PAUSE TestGCMetrics474=== RUN TestGCTaskStore_StartNew475=== PAUSE TestGCTaskStore_StartNew476=== RUN TestGCTaskStore_DeduplicateSameParams477=== PAUSE TestGCTaskStore_DeduplicateSameParams478=== RUN TestGCTaskStore_ConflictDifferentParams479=== PAUSE TestGCTaskStore_ConflictDifferentParams480=== RUN TestGCTaskStore_GetEmpty481=== PAUSE TestGCTaskStore_GetEmpty482=== RUN TestGCTaskStore_GetReturnsLatest483=== PAUSE TestGCTaskStore_GetReturnsLatest484=== RUN TestGCTaskStore_CompletedAllowsNewTask485=== PAUSE TestGCTaskStore_CompletedAllowsNewTask486=== RUN TestGCTaskStore_PhaseUpdates487=== PAUSE TestGCTaskStore_PhaseUpdates488=== RUN TestGCTaskStore_Fail489=== PAUSE TestGCTaskStore_Fail490=== RUN TestGracefulShutdownDrainsInflight491=== PAUSE TestGracefulShutdownDrainsInflight492=== RUN TestService_healthCheckHandler493=== PAUSE TestService_healthCheckHandler494=== RUN TestService_readinessHandler495=== PAUSE TestService_readinessHandler496=== RUN TestGenerateLandingPage497=== PAUSE TestGenerateLandingPage498=== RUN TestCacheConfigHandlerMaxNarSize499=== PAUSE TestCacheConfigHandlerMaxNarSize500=== RUN TestCreatePendingClosureRejectsOversizedNAR501=== PAUSE TestCreatePendingClosureRejectsOversizedNAR502=== RUN TestNARDeduplicationMetadataUploadBug503=== PAUSE TestNARDeduplicationMetadataUploadBug504=== RUN TestMetricsInventory505=== PAUSE TestMetricsInventory506=== RUN TestService_NativeMTLS507=== PAUSE TestService_NativeMTLS508=== RUN TestServerTLSConfig509=== PAUSE TestServerTLSConfig510=== RUN TestMultipartCleanup511=== PAUSE TestMultipartCleanup512=== RUN TestObjectStatsTrigger513=== PAUSE TestObjectStatsTrigger514=== RUN TestOrphanedObjectsGC515=== PAUSE TestOrphanedObjectsGC516=== RUN TestOrphanedObjectsGCStressTest517=== PAUSE TestOrphanedObjectsGCStressTest518=== RUN TestResurrectedObjectNotDeleted519=== PAUSE TestResurrectedObjectNotDeleted520=== RUN TestParseSingleRange521=== PAUSE TestParseSingleRange522=== RUN TestIsValidCachePath523=== PAUSE TestIsValidCachePath524=== RUN TestReadProxyNarinfo525=== PAUSE TestReadProxyNarinfo526=== RUN TestReadProxyNarinfoAlreadyDecompressed527=== PAUSE TestReadProxyNarinfoAlreadyDecompressed528=== RUN TestReadProxyNarStreaming529=== PAUSE TestReadProxyNarStreaming530=== RUN TestReadProxy404531=== PAUSE TestReadProxy404532=== RUN TestReadProxyInvalidPath533=== PAUSE TestReadProxyInvalidPath534=== RUN TestReadProxyHead535=== PAUSE TestReadProxyHead536=== RUN TestReadProxyConditionalGet537=== PAUSE TestReadProxyConditionalGet538=== RUN TestReadProxyRootRedirectsToIndexHTML539=== PAUSE TestReadProxyRootRedirectsToIndexHTML540=== RUN TestReadProxyDisabled541=== PAUSE TestReadProxyDisabled542=== RUN TestReadRedirectNar543=== PAUSE TestReadRedirectNar544=== RUN TestReadRedirectKeepsNarinfoProxied545=== PAUSE TestReadRedirectKeepsNarinfoProxied546=== RUN TestReadProxyRangeRequest547=== PAUSE TestReadProxyRangeRequest548=== RUN TestReadRedirectUsesPublicS3URL549=== PAUSE TestReadRedirectUsesPublicS3URL550=== RUN TestRedundantMultipartUpload551=== PAUSE TestRedundantMultipartUpload552=== RUN TestCompleteMultipartUpload_ErrorButObjectExists553=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists554=== RUN TestCompletedNarNotReofferedAcrossClosures555=== PAUSE TestCompletedNarNotReofferedAcrossClosures556=== RUN TestPresignedUploadRegisteredBeforeCommit557=== PAUSE TestPresignedUploadRegisteredBeforeCommit558=== RUN TestService_Rustfstest559=== PAUSE TestService_Rustfstest560=== RUN TestParseSize561=== PAUSE TestParseSize562=== RUN TestSkippedUploadsHandler563=== PAUSE TestSkippedUploadsHandler564=== RUN TestSystemdListenerNotActivated565--- PASS: TestSystemdListenerNotActivated (0.00s)566=== RUN TestWatchdogBeatsWhenHealthy567--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)568=== RUN TestWatchdogSkipsWhenUnhealthy5692026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5702026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5712026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5722026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5732026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5742026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5752026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5762026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5772026/09/21 12:56:32 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"578--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)579=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle580=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle581=== RUN TestProxyWriteTimeout582=== PAUSE TestProxyWriteTimeout583=== RUN TestIsValidUploadKey584=== PAUSE TestIsValidUploadKey585=== RUN TestUploadHandlersRejectInvalidKeys586=== PAUSE TestUploadHandlersRejectInvalidKeys587=== RUN TestUploadHandlersRejectOversizedBody588=== PAUSE TestUploadHandlersRejectOversizedBody589=== RUN TestService_cleanupPendingClosuresHandler590=== PAUSE TestService_cleanupPendingClosuresHandler591=== RUN TestService_createPendingClosureHandler592=== PAUSE TestService_createPendingClosureHandler593=== RUN TestService_verifyS3Integrity594=== PAUSE TestService_verifyS3Integrity595=== RUN TestCompleteMultipartUnregistered596=== PAUSE TestCompleteMultipartUnregistered597=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT598=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT599=== CONT TestPresignedUploadRegisteredBeforeCommit600=== CONT TestService_AuthMiddleware601=== CONT TestCompletedNarNotReofferedAcrossClosures602=== CONT TestService_healthCheckHandler603=== CONT TestGracefulShutdownDrainsInflight604=== CONT TestGCTaskStore_Fail605--- PASS: TestGCTaskStore_Fail (0.00s)606=== CONT TestResolveDBConnectionString607=== RUN TestResolveDBConnectionString/flag_wins6082026/09/21 12:56:32 INFO Starting HTTP server address=127.0.0.1:45455609=== PAUSE TestResolveDBConnectionString/flag_wins610=== RUN TestResolveDBConnectionString/file_when_flag_empty611=== PAUSE TestResolveDBConnectionString/file_when_flag_empty612=== RUN TestResolveDBConnectionString/missing_file_is_an_error613=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error614=== RUN TestResolveDBConnectionString/PGHOST_allows_empty615=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty616=== RUN TestResolveDBConnectionString/nothing_configured617=== PAUSE TestResolveDBConnectionString/nothing_configured618=== CONT TestGCTaskStore_PhaseUpdates619--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)620=== CONT TestPinProtectsFromGC621=== CONT TestReadProxyDisabled622=== CONT TestGCTaskStore_CompletedAllowsNewTask623--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)624=== CONT TestReadProxyRootRedirectsToIndexHTML6252026/09/21 12:56:32 INFO Shutdown signal received, draining in-flight requests timeout=10s626=== CONT TestGCTaskStore_GetReturnsLatest627--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)628=== CONT TestClientSharedPathCommittedMidPush629=== CONT TestGCTaskStore_GetEmpty630--- PASS: TestGCTaskStore_GetEmpty (0.00s)631=== CONT TestReadProxyConditionalGet632=== CONT TestGCTaskStore_ConflictDifferentParams633--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)634=== CONT TestService_readinessHandler635=== CONT TestGCTaskStore_DeduplicateSameParams636=== CONT TestCompleteMultipartUpload_ErrorButObjectExists637=== CONT TestGCTaskStore_StartNew638=== CONT TestRedundantMultipartUpload639=== CONT TestGCMetrics640=== CONT TestReadRedirectUsesPublicS3URL641=== CONT TestGCBugBareHashReferences642=== CONT TestReadProxyRangeRequest643=== CONT TestLeadEndsOnShutdown644=== CONT TestReadRedirectKeepsNarinfoProxied645=== CONT TestLeadElectsOneAndHandsOver646=== CONT TestReadRedirectNar647=== CONT TestClientWithDependencies648--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)649--- PASS: TestGCTaskStore_StartNew (0.00s)650=== CONT TestClientMultipleUploads651=== CONT TestReadProxyHead652--- PASS: TestGracefulShutdownDrainsInflight (0.08s)653=== CONT TestReadProxyInvalidPath6542026-09-21 12:56:32.399 UTC [450] ERROR: relation "goose_db_version" does not exist at character 366552026-09-21 12:56:32.399 UTC [450] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6562026-09-21 12:56:32.400 UTC [449] ERROR: relation "goose_db_version" does not exist at character 366572026-09-21 12:56:32.400 UTC [449] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6582026-09-21 12:56:32.437 UTC [451] ERROR: relation "goose_db_version" does not exist at character 366592026-09-21 12:56:32.437 UTC [451] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6602026-09-21 12:56:32.439 UTC [452] ERROR: relation "goose_db_version" does not exist at character 366612026-09-21 12:56:32.439 UTC [452] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6622026-09-21 12:56:32.498 UTC [454] ERROR: relation "goose_db_version" does not exist at character 366632026-09-21 12:56:32.498 UTC [454] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6642026-09-21 12:56:32.498 UTC [456] ERROR: relation "goose_db_version" does not exist at character 366652026-09-21 12:56:32.498 UTC [456] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6662026-09-21 12:56:32.499 UTC [455] ERROR: relation "goose_db_version" does not exist at character 366672026-09-21 12:56:32.499 UTC [455] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6682026-09-21 12:56:32.512 UTC [457] ERROR: relation "goose_db_version" does not exist at character 366692026-09-21 12:56:32.512 UTC [457] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6702026/09/21 12:56:32 OK 20241026095416_initial_model.sql (201.8ms)6712026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (18.96ms)6722026/09/21 12:56:32 OK 20241026095416_initial_model.sql (130.96ms)6732026/09/21 12:56:32 OK 20241026095416_initial_model.sql (188.92ms)6742026/09/21 12:56:32 OK 20241026095416_initial_model.sql (119.82ms)6752026/09/21 12:56:32 OK 20241026095416_initial_model.sql (227.98ms)6762026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (9.45ms)6772026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (12.43ms)6782026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (6.42ms)6792026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (6.02ms)6802026/09/21 12:56:32 OK 20251218171726_add_pins.sql (19.61ms)6812026/09/21 12:56:32 OK 20241026095416_initial_model.sql (204.73ms)6822026/09/21 12:56:32 OK 20251218171726_add_pins.sql (6.13ms)6832026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (4.41ms)6842026-09-21 12:56:32.692 UTC [458] ERROR: relation "goose_db_version" does not exist at character 366852026-09-21 12:56:32.692 UTC [458] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6862026/09/21 12:56:32 OK 20251218171726_add_pins.sql (16.14ms)6872026/09/21 12:56:32 OK 20251218171726_add_pins.sql (18.65ms)6882026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (15.77ms)6892026/09/21 12:56:32 OK 20251218171726_add_pins.sql (18.72ms)6902026/09/21 12:56:32 OK 20251218171726_add_pins.sql (13.85ms)6912026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (21.28ms)6922026/09/21 12:56:32 OK 20260905000000_add_claims.sql (8.88ms)6932026/09/21 12:56:32 OK 20241026095416_initial_model.sql (40.61ms)6942026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (12.74ms)6952026/09/21 12:56:32 OK 20260905000000_add_claims.sql (8.84ms)6962026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (12.78ms)6972026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (20.88ms)6982026/09/21 12:56:32 OK 20241026095416_initial_model.sql (60.66ms)6992026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (14.28ms)7002026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007012026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (14.26ms)7022026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (14.6ms)7032026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007042026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (12.27ms)7052026/09/21 12:56:32 OK 20260905000000_add_claims.sql (25.32ms)7062026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (40.24ms)7072026/09/21 12:56:32 OK 20260905000000_add_claims.sql (25.57ms)7082026/09/21 12:56:32 OK 1_commit_pending_closure.sql (15.3ms)7092026/09/21 12:56:32 OK 1_commit_pending_closure.sql (11.18ms)7102026-09-21 12:56:32.735 UTC [459] ERROR: relation "goose_db_version" does not exist at character 367112026-09-21 12:56:32.735 UTC [459] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7122026/09/21 12:56:32 OK 20251218171726_add_pins.sql (19.96ms)7132026/09/21 12:56:32 OK 20260905000000_add_claims.sql (27.1ms)7142026/09/21 12:56:32 OK 2_object_stats_trigger.sql (10.81ms)7152026/09/21 12:56:32 goose: up to current file version: 27162026/09/21 12:56:32 OK 2_object_stats_trigger.sql (10.83ms)7172026/09/21 12:56:32 goose: up to current file version: 27182026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (12.57ms)7192026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007202026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (13.64ms)7212026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007222026-09-21 12:56:32.749 UTC [460] ERROR: relation "goose_db_version" does not exist at character 367232026-09-21 12:56:32.749 UTC [460] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7242026/09/21 12:56:32 OK 20260905000000_add_claims.sql (17.02ms)7252026/09/21 12:56:32 OK 1_commit_pending_closure.sql (7.78ms)7262026/09/21 12:56:32 OK 20251218171726_add_pins.sql (24.28ms)7272026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (10.44ms)7282026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007292026-09-21 12:56:32.758 UTC [461] ERROR: relation "goose_db_version" does not exist at character 367302026-09-21 12:56:32.758 UTC [461] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7312026/09/21 12:56:32 OK 2_object_stats_trigger.sql (8.11ms)7322026/09/21 12:56:32 goose: up to current file version: 27332026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (21.32ms)7342026/09/21 12:56:32 OK 1_commit_pending_closure.sql (16.46ms)7352026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (14.11ms)7362026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007372026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (10.63ms)7382026/09/21 12:56:32 OK 1_commit_pending_closure.sql (9.7ms)7392026-09-21 12:56:32.776 UTC [462] ERROR: relation "goose_db_version" does not exist at character 367402026-09-21 12:56:32.776 UTC [462] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7412026/09/21 12:56:32 OK 1_commit_pending_closure.sql (13.22ms)7422026/09/21 12:56:32 OK 2_object_stats_trigger.sql (14.38ms)7432026/09/21 12:56:32 goose: up to current file version: 27442026/09/21 12:56:32 OK 20260905000000_add_claims.sql (13.28ms)7452026/09/21 12:56:32 OK 2_object_stats_trigger.sql (13.21ms)7462026/09/21 12:56:32 goose: up to current file version: 27472026-09-21 12:56:32.779 UTC [463] ERROR: relation "goose_db_version" does not exist at character 367482026-09-21 12:56:32.779 UTC [463] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7492026/09/21 12:56:32 OK 20260905000000_add_claims.sql (18.91ms)7502026/09/21 12:56:32 OK 20241026095416_initial_model.sql (73.08ms)7512026/09/21 12:56:32 OK 2_object_stats_trigger.sql (7.74ms)7522026/09/21 12:56:32 goose: up to current file version: 27532026/09/21 12:56:32 OK 20241026095416_initial_model.sql (30.7ms)7542026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (8.94ms)7552026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007562026-09-21 12:56:32.789 UTC [465] ERROR: relation "goose_db_version" does not exist at character 367572026-09-21 12:56:32.789 UTC [465] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7582026-09-21 12:56:32.790 UTC [464] ERROR: relation "goose_db_version" does not exist at character 367592026-09-21 12:56:32.790 UTC [464] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7602026-09-21 12:56:32.794 UTC [467] ERROR: relation "goose_db_version" does not exist at character 367612026-09-21 12:56:32.794 UTC [467] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7622026-09-21 12:56:32.795 UTC [466] ERROR: relation "goose_db_version" does not exist at character 367632026-09-21 12:56:32.795 UTC [466] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7642026/09/21 12:56:32 OK 1_commit_pending_closure.sql (11.26ms)7652026/09/21 12:56:32 OK 20241026095416_initial_model.sql (20.64ms)7662026-09-21 12:56:32.799 UTC [469] ERROR: relation "goose_db_version" does not exist at character 367672026-09-21 12:56:32.799 UTC [469] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7682026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (14.7ms)7692026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (17.06ms)7702026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000007712026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (13.57ms)7722026/09/21 12:56:32 OK 20241026095416_initial_model.sql (33.33ms)7732026-09-21 12:56:32.802 UTC [468] ERROR: relation "goose_db_version" does not exist at character 367742026-09-21 12:56:32.802 UTC [468] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7752026/09/21 12:56:32 OK 2_object_stats_trigger.sql (7.77ms)7762026/09/21 12:56:32 goose: up to current file version: 27772026-09-21 12:56:32.807 UTC [470] ERROR: relation "goose_db_version" does not exist at character 367782026-09-21 12:56:32.807 UTC [470] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7792026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (9.02ms)7802026-09-21 12:56:32.811 UTC [471] ERROR: relation "goose_db_version" does not exist at character 367812026-09-21 12:56:32.811 UTC [471] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7822026-09-21 12:56:32.811 UTC [472] ERROR: relation "goose_db_version" does not exist at character 367832026-09-21 12:56:32.811 UTC [472] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7842026/09/21 12:56:32 OK 20241026095416_initial_model.sql (18.76ms)7852026/09/21 12:56:32 OK 20251218171726_add_pins.sql (15.63ms)7862026/09/21 12:56:32 OK 20251218171726_add_pins.sql (15.3ms)7872026-09-21 12:56:32.816 UTC [473] ERROR: relation "goose_db_version" does not exist at character 367882026-09-21 12:56:32.816 UTC [473] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC7892026/09/21 12:56:32 OK 20241026095416_initial_model.sql (17.83ms)7902026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (16.86ms)7912026/09/21 12:56:32 OK 20251218171726_add_pins.sql (8.35ms)7922026/09/21 12:56:32 OK 1_commit_pending_closure.sql (11.98ms)7932026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (9.24ms)7942026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (7.84ms)7952026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (10.7ms)7962026/09/21 12:56:32 OK 20241026095416_initial_model.sql (19.13ms)7972026/09/21 12:56:32 OK 20251218171726_add_pins.sql (8.64ms)7982026/09/21 12:56:32 OK 2_object_stats_trigger.sql (8.12ms)7992026/09/21 12:56:32 goose: up to current file version: 28002026/09/21 12:56:32 OK 20241026095416_initial_model.sql (15.85ms)8012026/09/21 12:56:32 OK 20241026095416_initial_model.sql (19.15ms)8022026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (11.2ms)8032026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (11.56ms)8042026/09/21 12:56:32 OK 20251218171726_add_pins.sql (5.03ms)8052026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (4.07ms)8062026/09/21 12:56:32 OK 20260905000000_add_claims.sql (8.27ms)8072026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (6.23ms)8082026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (5.68ms)8092026/09/21 12:56:32 OK 20251218171726_add_pins.sql (15.5ms)8102026/09/21 12:56:32 OK 20241026095416_initial_model.sql (25.08ms)8112026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (14.73ms)8122026/09/21 12:56:32 OK 20241026095416_initial_model.sql (23.73ms)8132026/09/21 12:56:32 OK 20241026095416_initial_model.sql (21.22ms)8142026/09/21 12:56:32 OK 20260905000000_add_claims.sql (11.73ms)8152026/09/21 12:56:32 OK 20260905000000_add_claims.sql (14.21ms)8162026/09/21 12:56:32 OK 20241026095416_initial_model.sql (20.94ms)8172026/09/21 12:56:32 OK 20241026095416_initial_model.sql (21.04ms)8182026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.59ms)8192026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.59ms)8202026/09/21 12:56:32 OK 20241026095416_initial_model.sql (22.8ms)8212026/09/21 12:56:32 OK 20251218171726_add_pins.sql (13.1ms)8222026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (14.5ms)8232026/09/21 12:56:32 OK 20241026095416_initial_model.sql (12.75ms)8242026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (3.88ms)8252026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (4.32ms)8262026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (4.45ms)8272026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (4.21ms)8282026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008292026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (3.82ms)8302026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (4.95ms)8312026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008322026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (13.1ms)8332026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008342026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (4.19ms)8352026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (9.47ms)8362026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (8.53ms)8372026/09/21 12:56:32 OK 1_commit_pending_closure.sql (5.28ms)8382026/09/21 12:56:32 OK 20260905000000_add_claims.sql (10.61ms)8392026/09/21 12:56:32 OK 20251210153512_drop_unused_gin_index.sql (6.89ms)8402026/09/21 12:56:32 OK 1_commit_pending_closure.sql (8.41ms)8412026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (10.65ms)8422026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.46ms)8432026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.39ms)8442026/09/21 12:56:32 OK 1_commit_pending_closure.sql (9.19ms)8452026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (10.99ms)8462026/09/21 12:56:32 OK 20260905000000_add_claims.sql (10ms)8472026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.8ms)8482026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (11.09ms)8492026/09/21 12:56:32 OK 20251218171726_add_pins.sql (8.97ms)8502026/09/21 12:56:32 OK 20260905000000_add_claims.sql (7.67ms)8512026/09/21 12:56:32 OK 2_object_stats_trigger.sql (6.63ms)8522026/09/21 12:56:32 goose: up to current file version: 28532026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.98ms)8542026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (8.04ms)8552026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008562026/09/21 12:56:32 OK 20251218171726_add_pins.sql (9.79ms)8572026/09/21 12:56:32 OK 2_object_stats_trigger.sql (4.24ms)8582026/09/21 12:56:32 goose: up to current file version: 28592026/09/21 12:56:32 OK 20260905000000_add_claims.sql (5.5ms)8602026/09/21 12:56:32 OK 20251218171726_add_pins.sql (8.63ms)8612026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.32ms)8622026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (6.26ms)8632026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (6.2ms)8642026/09/21 12:56:32 OK 2_object_stats_trigger.sql (6.4ms)8652026/09/21 12:56:32 goose: up to current file version: 28662026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (6.47ms)8672026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.2ms)8682026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (7.04ms)8692026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (7.39ms)8702026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008712026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (4.41ms)8722026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008732026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (5.2ms)8742026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008752026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (5.83ms)8762026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (5.25ms)8772026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008782026/09/21 12:56:32 OK 1_commit_pending_closure.sql (7.21ms)8792026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (8.74ms)8802026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (5.2ms)8812026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000008822026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.01ms)8832026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.47ms)8842026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.26ms)8852026/09/21 12:56:32 OK 20260628120000_add_object_size_and_stats.sql (6.99ms)8862026/09/21 12:56:32 OK 1_commit_pending_closure.sql (5.42ms)8872026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.56ms)8882026/09/21 12:56:32 OK 1_commit_pending_closure.sql (5.16ms)8892026/09/21 12:56:32 OK 1_commit_pending_closure.sql (6.03ms)8902026/09/21 12:56:32 OK 1_commit_pending_closure.sql (6.67ms)8912026/09/21 12:56:32 OK 20260905000000_add_claims.sql (6.69ms)8922026/09/21 12:56:32 OK 20260905000000_add_claims.sql (7.62ms)8932026/09/21 12:56:32 OK 2_object_stats_trigger.sql (6.84ms)8942026/09/21 12:56:32 goose: up to current file version: 28952026/09/21 12:56:32 OK 2_object_stats_trigger.sql (5.33ms)8962026/09/21 12:56:32 goose: up to current file version: 28972026/09/21 12:56:32 OK 20260905000000_add_claims.sql (5.4ms)8982026/09/21 12:56:32 OK 2_object_stats_trigger.sql (5.36ms)8992026/09/21 12:56:32 goose: up to current file version: 29002026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (6.34ms)9012026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009022026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (6.12ms)9032026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009042026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (6.34ms)9052026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009062026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (6.88ms)9072026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009082026/09/21 12:56:32 OK 1_commit_pending_closure.sql (7.91ms)9092026/09/21 12:56:32 OK 2_object_stats_trigger.sql (3.76ms)9102026/09/21 12:56:32 goose: up to current file version: 29112026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.6ms)9122026/09/21 12:56:32 goose: up to current file version: 29132026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (3.01ms)9142026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009152026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (4.53ms)9162026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009172026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.9ms)9182026/09/21 12:56:32 OK 20260920000000_drop_claims.sql (4.43ms)9192026/09/21 12:56:32 goose: successfully migrated database to version: 202609200000009202026/09/21 12:56:32 goose: up to current file version: 29212026/09/21 12:56:32 OK 1_commit_pending_closure.sql (4.24ms)9222026/09/21 12:56:32 OK 1_commit_pending_closure.sql (2.85ms)9232026/09/21 12:56:32 OK 1_commit_pending_closure.sql (5.15ms)9242026/09/21 12:56:32 OK 1_commit_pending_closure.sql (4.54ms)9252026/09/21 12:56:32 OK 1_commit_pending_closure.sql (5.25ms)9262026/09/21 12:56:32 OK 1_commit_pending_closure.sql (3.24ms)9272026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.36ms)9282026/09/21 12:56:32 goose: up to current file version: 29292026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.05ms)9302026/09/21 12:56:32 goose: up to current file version: 29312026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.15ms)9322026/09/21 12:56:32 goose: up to current file version: 29332026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.09ms)9342026/09/21 12:56:32 goose: up to current file version: 29352026/09/21 12:56:32 OK 2_object_stats_trigger.sql (2.58ms)9362026/09/21 12:56:32 goose: up to current file version: 29372026/09/21 12:56:32 OK 1_commit_pending_closure.sql (4.32ms)9382026/09/21 12:56:32 OK 2_object_stats_trigger.sql (1.33ms)9392026/09/21 12:56:32 goose: up to current file version: 29402026/09/21 12:56:32 OK 2_object_stats_trigger.sql (1.15ms)9412026/09/21 12:56:32 goose: up to current file version: 2942--- PASS: TestService_healthCheckHandler (3.19s)943=== CONT TestClientIntegration9442026-09-21 12:56:35.634 UTC [476] ERROR: relation "goose_db_version" does not exist at character 369452026-09-21 12:56:35.634 UTC [476] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9462026/09/21 12:56:35 OK 20241026095416_initial_model.sql (34.71ms)9472026/09/21 12:56:35 OK 20251210153512_drop_unused_gin_index.sql (54.35ms)9482026/09/21 12:56:35 OK 20251218171726_add_pins.sql (28.61ms)9492026/09/21 12:56:35 OK 20260628120000_add_object_size_and_stats.sql (4.08ms)9502026/09/21 12:56:35 OK 20260905000000_add_claims.sql (6.31ms)9512026/09/21 12:56:35 OK 20260920000000_drop_claims.sql (7.86ms)9522026/09/21 12:56:35 goose: successfully migrated database to version: 202609200000009532026/09/21 12:56:35 OK 1_commit_pending_closure.sql (7.92ms)9542026/09/21 12:56:35 OK 2_object_stats_trigger.sql (3.3ms)9552026/09/21 12:56:35 goose: up to current file version: 2956=== NAME TestPinProtectsFromGC957 client_integration_test.go:731: Pinned store path: /build/TestPinProtectsFromGC1502258940/001/store/wjydvvqp87jwx6jk92wi8a6fqdzmzdb8-pinned-file.txt958 client_integration_test.go:732: Unpinned store path: /build/TestPinProtectsFromGC1502258940/001/store/v0659lqmzi1i89898kp1ms0fwjdiwsax-unpinned-file.txt9592026/09/21 12:56:36 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"960--- PASS: TestService_AuthMiddleware (4.11s)961=== CONT TestReadProxy4049622026/09/21 12:56:36 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"9632026/09/21 12:56:36 INFO Received uploads request method=POST path=/api/pending_closures9642026/09/21 12:56:36 INFO Received uploads request method=POST path=/api/pending_closures9652026/09/21 12:56:36 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)9662026/09/21 12:56:36 INFO Uploading wjydvvqp87jwx6jk92wi8a6fqdzmzdb8-pinned-file.txt (128B)9672026-09-21 12:56:36.445 UTC [570] ERROR: relation "goose_db_version" does not exist at character 369682026-09-21 12:56:36.445 UTC [570] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9692026/09/21 12:56:36 OK 20241026095416_initial_model.sql (10.56ms)9702026/09/21 12:56:36 OK 20251210153512_drop_unused_gin_index.sql (1.98ms)9712026/09/21 12:56:36 OK 20251218171726_add_pins.sql (7.42ms)9722026/09/21 12:56:36 OK 20260628120000_add_object_size_and_stats.sql (5.74ms)9732026/09/21 12:56:36 OK 20260905000000_add_claims.sql (4.71ms)9742026/09/21 12:56:36 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"9752026/09/21 12:56:36 OK 20260920000000_drop_claims.sql (6.94ms)9762026/09/21 12:56:36 goose: successfully migrated database to version: 20260920000000977--- PASS: TestReadProxyRootRedirectsToIndexHTML (4.24s)978=== CONT TestClientErrorHandling979=== RUN TestClientErrorHandling/InvalidStorePath980=== PAUSE TestClientErrorHandling/InvalidStorePath981=== RUN TestClientErrorHandling/InvalidAuthToken982=== PAUSE TestClientErrorHandling/InvalidAuthToken983=== RUN TestClientErrorHandling/ServerNotAvailable984=== PAUSE TestClientErrorHandling/ServerNotAvailable985=== CONT TestReadProxyNarStreaming9862026/09/21 12:56:36 WARN Failed to register uploaded object key=wjydvvqp87jwx6jk92wi8a6fqdzmzdb8.ls error="server returned 404: 404 page not found\n"9872026/09/21 12:56:36 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign9882026/09/21 12:56:36 INFO Signed narinfos id=1 count=19892026/09/21 12:56:36 INFO Uploading 1 narinfos9902026/09/21 12:56:36 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst9912026/09/21 12:56:36 INFO Received uploads request method=POST path=/api/pending_closures9922026/09/21 12:56:36 OK 1_commit_pending_closure.sql (22.81ms)9932026/09/21 12:56:36 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9942026/09/21 12:56:36 WARN Failed to register uploaded object key=wjydvvqp87jwx6jk92wi8a6fqdzmzdb8.narinfo error="server returned 404: 404 page not found\n"9952026/09/21 12:56:37 INFO Received uploads request method=POST path=/api/pending_closures996--- PASS: TestPresignedUploadRegisteredBeforeCommit (5.26s)997=== CONT TestClientCADerivations9982026/09/21 12:56:37 OK 2_object_stats_trigger.sql (930.21ms)9992026/09/21 12:56:37 goose: up to current file version: 210002026/09/21 12:56:37 INFO Completed upload id=110012026/09/21 12:56:37 INFO Upload complete. (1.313s)10022026-09-21 12:56:39.206 UTC [576] ERROR: relation "goose_db_version" does not exist at character 3610032026-09-21 12:56:39.206 UTC [576] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10042026/09/21 12:56:40 OK 20241026095416_initial_model.sql (343.11ms)10052026/09/21 12:56:40 OK 20251210153512_drop_unused_gin_index.sql (190.72ms)10062026/09/21 12:56:40 OK 20251218171726_add_pins.sql (160.55ms)10072026/09/21 12:56:40 OK 20260628120000_add_object_size_and_stats.sql (144.8ms)10082026/09/21 12:56:40 OK 20260905000000_add_claims.sql (104.13ms)10092026/09/21 12:56:40 OK 20260920000000_drop_claims.sql (129.46ms)10102026/09/21 12:56:40 goose: successfully migrated database to version: 2026092000000010112026/09/21 12:56:41 OK 1_commit_pending_closure.sql (193.44ms)10122026-09-21 12:56:41.101 UTC [595] ERROR: relation "goose_db_version" does not exist at character 3610132026-09-21 12:56:41.101 UTC [595] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10142026/09/21 12:56:41 OK 2_object_stats_trigger.sql (70.2ms)10152026/09/21 12:56:41 goose: up to current file version: 210162026/09/21 12:56:41 OK 20241026095416_initial_model.sql (240.61ms)10172026/09/21 12:56:41 OK 20251210153512_drop_unused_gin_index.sql (91.62ms)10182026/09/21 12:56:41 OK 20251218171726_add_pins.sql (116.84ms)10192026/09/21 12:56:42 OK 20260628120000_add_object_size_and_stats.sql (118.02ms)10202026/09/21 12:56:42 OK 20260905000000_add_claims.sql (110.91ms)10212026/09/21 12:56:42 OK 20260920000000_drop_claims.sql (90.51ms)10222026/09/21 12:56:42 goose: successfully migrated database to version: 2026092000000010232026/09/21 12:56:42 OK 1_commit_pending_closure.sql (164.35ms)10242026/09/21 12:56:42 OK 2_object_stats_trigger.sql (93.09ms)10252026/09/21 12:56:42 goose: up to current file version: 21026=== NAME TestClientWithDependencies1027 client_integration_test.go:597: unexpected error: context deadline exceeded1028 1029--- FAIL: TestClientWithDependencies (10.26s)1030=== CONT TestReadProxyNarinfoAlreadyDecompressed1031=== NAME TestReadProxyDisabled1032 proxy_test.go:393: unexpected error: Put "http://localhost:38293/bucket8/": context deadline exceeded1033 1034--- FAIL: TestReadProxyDisabled (10.29s)1035=== CONT TestCacheStatsHandler1036=== NAME TestClientMultipleUploads1037 client_integration_test.go:330: unexpected error: Put "http://localhost:38293/bucket10/": context deadline exceeded1038 1039=== NAME TestLeadEndsOnShutdown1040 farm_test.go:134: unexpected error: context deadline exceeded1041=== NAME TestService_readinessHandler1042 health_test.go:28: unexpected error: context deadline exceeded1043=== NAME TestReadProxyConditionalGet1044 proxy_test.go:304: unexpected error: context deadline exceeded1045=== NAME TestReadProxyRangeRequest1046 proxy_test.go:496: unexpected error: context deadline exceeded1047=== NAME TestReadRedirectUsesPublicS3URL1048 proxy_test.go:583: unexpected error: context deadline exceeded1049=== NAME TestLeadElectsOneAndHandsOver1050 farm_test.go:102: unexpected error: context deadline exceeded1051=== NAME TestCompleteMultipartUpload_ErrorButObjectExists1052 redundant_multipart_test.go:79: unexpected error: context deadline exceeded1053=== NAME TestClientSharedPathCommittedMidPush1054 client_integration_test.go:643: unexpected error: Put "http://localhost:38293/bucket18/": context deadline exceeded1055--- FAIL: TestClientMultipleUploads (10.52s)1056=== CONT TestReadProxyNarinfo1057=== NAME TestService_readinessHandler1058 1059=== NAME TestLeadEndsOnShutdown1060 1061=== NAME TestReadProxyConditionalGet1062 1063=== NAME TestReadProxyRangeRequest1064 1065=== NAME TestReadRedirectUsesPublicS3URL1066 1067=== NAME TestLeadElectsOneAndHandsOver1068 1069=== NAME TestClientSharedPathCommittedMidPush1070 1071=== NAME TestCompleteMultipartUpload_ErrorButObjectExists1072 1073=== NAME TestGCBugBareHashReferences1074 gc_bug_test.go:18: unexpected error: context deadline exceeded1075=== NAME TestReadProxyHead1076 proxy_test.go:262: unexpected error: Put "http://localhost:38293/bucket19/": context deadline exceeded1077=== NAME TestRedundantMultipartUpload1078 redundant_multipart_test.go:24: unexpected error: Put "http://localhost:38293/bucket21/": context deadline exceeded1079=== NAME TestReadRedirectNar1080 proxy_test.go:405: unexpected error: context deadline exceeded1081=== NAME TestReadProxyInvalidPath1082 proxy_test.go:244: unexpected error: context deadline exceeded1083--- FAIL: TestLeadElectsOneAndHandsOver (10.54s)1084=== CONT TestCacheConfigHandler1085=== NAME TestGCBugBareHashReferences1086 1087=== NAME TestReadProxyHead1088 1089=== NAME TestRedundantMultipartUpload1090 1091=== CONT TestIsValidCachePath1092=== NAME TestReadRedirectNar1093 1094=== NAME TestReadProxyInvalidPath1095 1096--- FAIL: TestReadProxyHead (10.56s)1097--- FAIL: TestRedundantMultipartUpload (10.57s)1098--- FAIL: TestService_readinessHandler (10.54s)1099--- FAIL: TestLeadEndsOnShutdown (10.56s)1100=== NAME TestReadRedirectKeepsNarinfoProxied1101 proxy_test.go:466: unexpected error: Put "http://localhost:38293/bucket24/": context deadline exceeded1102 1103=== CONT TestService_ReadScope_PublicByDefault1104=== CONT TestParseSingleRange1105=== NAME TestGCMetrics1106 gc_metrics_test.go:18: unexpected error: context deadline exceeded1107 1108=== RUN TestIsValidCachePath/narinfo1109=== CONT TestService_RequireScope_OIDC1110--- FAIL: TestClientSharedPathCommittedMidPush (10.57s)1111=== CONT TestResurrectedObjectNotDeleted1112=== RUN TestCacheConfigHandler/full_config,_no_issuer1113=== RUN TestParseSingleRange/none1114=== PAUSE TestParseSingleRange/none1115=== RUN TestParseSingleRange/unknown_unit1116=== PAUSE TestParseSingleRange/unknown_unit1117=== PAUSE TestIsValidCachePath/narinfo1118=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars1119--- FAIL: TestCompleteMultipartUpload_ErrorButObjectExists (10.60s)1120=== CONT TestService_AuthMiddleware_OIDC1121=== RUN TestParseSingleRange/multi-range_ignored1122=== PAUSE TestCacheConfigHandler/full_config,_no_issuer1123=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1124=== RUN TestIsValidCachePath/nar_zst1125=== PAUSE TestIsValidCachePath/nar_zst1126=== RUN TestIsValidCachePath/nar_xz1127=== PAUSE TestIsValidCachePath/nar_xz1128=== RUN TestIsValidCachePath/nar_bz21129=== PAUSE TestIsValidCachePath/nar_bz21130=== RUN TestIsValidCachePath/nar_uncompressed1131=== PAUSE TestIsValidCachePath/nar_uncompressed1132=== RUN TestIsValidCachePath/ls1133=== PAUSE TestIsValidCachePath/ls1134=== RUN TestIsValidCachePath/log1135=== PAUSE TestIsValidCachePath/log1136=== RUN TestIsValidCachePath/realisation1137=== PAUSE TestIsValidCachePath/realisation1138=== RUN TestIsValidCachePath/nix-cache-info1139=== PAUSE TestIsValidCachePath/nix-cache-info1140=== RUN TestIsValidCachePath/index.html1141=== PAUSE TestIsValidCachePath/index.html1142=== RUN TestIsValidCachePath/traversal_parent1143=== PAUSE TestParseSingleRange/multi-range_ignored1144=== CONT TestOrphanedObjectsGCStressTest1145=== CONT TestService_ReadAuthMiddleware1146=== CONT TestOrphanedObjectsGC1147=== CONT TestService_AuthMiddleware_MTLSBoundSubjects1148=== CONT TestObjectStatsTrigger1149=== RUN TestCacheConfigHandler/no_cache_url_configured1150=== CONT TestService_AuthMiddleware_MTLSProxyHeader1151--- FAIL: TestGCMetrics (10.66s)1152=== CONT TestMultipartCleanup1153=== RUN TestParseSingleRange/malformed_no_dash1154=== PAUSE TestIsValidCachePath/traversal_parent1155--- FAIL: TestReadRedirectNar (10.66s)1156=== PAUSE TestCacheConfigHandler/no_cache_url_configured1157=== RUN TestCacheConfigHandler/no_signing_keys1158=== PAUSE TestParseSingleRange/malformed_no_dash1159=== RUN TestParseSingleRange/malformed_both_empty1160=== RUN TestIsValidCachePath/traversal_in_middle1161--- FAIL: TestReadProxyInvalidPath (10.60s)1162--- FAIL: TestReadProxyRangeRequest (10.67s)1163--- FAIL: TestGCBugBareHashReferences (10.67s)1164--- FAIL: TestReadProxyConditionalGet (10.67s)1165--- FAIL: TestReadRedirectKeepsNarinfoProxied (10.74s)1166=== PAUSE TestParseSingleRange/malformed_both_empty1167=== PAUSE TestIsValidCachePath/traversal_in_middle1168=== PAUSE TestCacheConfigHandler/no_signing_keys1169=== CONT TestServerTLSConfig1170=== RUN TestServerTLSConfig/no_client_CA1171--- FAIL: TestReadRedirectUsesPublicS3URL (10.67s)1172=== PAUSE TestServerTLSConfig/no_client_CA11732026/09/21 12:56:43 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:36733/oidc1174=== RUN TestServerTLSConfig/missing_CA_file11752026/09/21 12:56:43 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:45021/oidc1176=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1177=== RUN TestParseSingleRange/malformed_end_before_start1178=== RUN TestIsValidCachePath/invalid_char_e1179=== PAUSE TestIsValidCachePath/invalid_char_e1180=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1181=== PAUSE TestServerTLSConfig/missing_CA_file1182=== RUN TestServerTLSConfig/not_a_PEM_file1183=== PAUSE TestServerTLSConfig/not_a_PEM_file1184=== CONT TestUploadHandlersRejectInvalidKeys1185=== RUN TestIsValidCachePath/invalid_char_u1186=== PAUSE TestIsValidCachePath/invalid_char_u1187=== RUN TestIsValidCachePath/random_path1188=== PAUSE TestIsValidCachePath/random_path1189=== RUN TestIsValidCachePath/empty1190=== PAUSE TestIsValidCachePath/empty1191=== RUN TestIsValidCachePath/leading_slash1192=== PAUSE TestIsValidCachePath/leading_slash1193=== RUN TestIsValidCachePath/wrong_extension1194=== PAUSE TestIsValidCachePath/wrong_extension1195=== RUN TestIsValidCachePath/short_hash1196=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1197=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1198=== CONT TestService_NativeMTLS1199=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1200=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1201=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1202=== PAUSE TestParseSingleRange/malformed_end_before_start1203=== RUN TestParseSingleRange/closed1204=== PAUSE TestIsValidCachePath/short_hash1205=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1206=== PAUSE TestParseSingleRange/closed1207=== RUN TestParseSingleRange/open-ended1208=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1209=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1210=== CONT TestMetricsInventory1211=== PAUSE TestParseSingleRange/open-ended1212=== RUN TestParseSingleRange/end_clamped_to_size1213=== PAUSE TestParseSingleRange/end_clamped_to_size1214=== RUN TestParseSingleRange/suffix1215=== PAUSE TestParseSingleRange/suffix1216=== RUN TestParseSingleRange/suffix_exceeds_size1217=== PAUSE TestParseSingleRange/suffix_exceeds_size1218=== RUN TestParseSingleRange/single_byte1219=== PAUSE TestParseSingleRange/single_byte1220=== RUN TestParseSingleRange/start_past_EOF1221=== PAUSE TestParseSingleRange/start_past_EOF1222=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT1223=== RUN TestParseSingleRange/start_far_past_EOF1224=== PAUSE TestParseSingleRange/start_far_past_EOF1225=== CONT TestCompleteMultipartUnregistered12262026/09/21 12:56:43 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"1227=== NAME TestClientIntegration1228 client_integration_test.go:254: unexpected error: Put "http://localhost:38293/bucket26/": context deadline exceeded1229 1230--- FAIL: TestClientIntegration (10.21s)1231=== CONT TestService_verifyS3Integrity1232=== NAME TestReadProxy4041233 proxy_test.go:231: unexpected error: Put "http://localhost:38293/bucket27/": context deadline exceeded1234 1235--- FAIL: TestReadProxy404 (10.23s)1236=== CONT TestNARDeduplicationMetadataUploadBug12372026/09/21 12:56:47 INFO Received uploads request method=POST path=/api/pending_closures12382026/09/21 12:56:48 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12392026/09/21 12:56:48 INFO Uploading v0659lqmzi1i89898kp1ms0fwjdiwsax-unpinned-file.txt (128B)1240=== NAME TestReadProxyNarStreaming1241 proxy_test.go:208: unexpected error: Put "http://localhost:38293/bucket28/": context deadline exceeded1242 1243--- FAIL: TestReadProxyNarStreaming (12.37s)1244=== CONT TestService_createPendingClosureHandler12452026-09-21 12:56:49.447 UTC [671] ERROR: relation "goose_db_version" does not exist at character 3612462026-09-21 12:56:49.447 UTC [671] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1247=== NAME TestClientCADerivations1248 client_ca_test.go:118: unexpected error: Put "http://localhost:38293/bucket29/": context deadline exceeded1249 1250--- FAIL: TestClientCADerivations (12.93s)1251=== CONT TestService_cleanupPendingClosuresHandler12522026-09-21 12:56:50.487 UTC [673] ERROR: relation "goose_db_version" does not exist at character 3612532026-09-21 12:56:50.487 UTC [673] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12542026-09-21 12:56:50.556 UTC [675] ERROR: relation "goose_db_version" does not exist at character 3612552026-09-21 12:56:50.556 UTC [675] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12562026/09/21 12:56:54 OK 20241026095416_initial_model.sql (3.79s)12572026/09/21 12:56:55 OK 20251210153512_drop_unused_gin_index.sql (894.38ms)12582026/09/21 12:56:55 OK 20241026095416_initial_model.sql (722.64ms)12592026-09-21 12:56:56.381 UTC [678] ERROR: relation "goose_db_version" does not exist at character 3612602026-09-21 12:56:56.381 UTC [678] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12612026/09/21 12:56:56 OK 20241026095416_initial_model.sql (1.28s)12622026/09/21 12:56:56 OK 20251218171726_add_pins.sql (748.97ms)12632026/09/21 12:56:56 OK 20251210153512_drop_unused_gin_index.sql (706.55ms)12642026-09-21 12:56:57.689 UTC [679] ERROR: relation "goose_db_version" does not exist at character 3612652026-09-21 12:56:57.689 UTC [679] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12662026-09-21 12:56:58.353 UTC [680] ERROR: relation "goose_db_version" does not exist at character 3612672026-09-21 12:56:58.353 UTC [680] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12682026-09-21 12:56:58.854 UTC [681] ERROR: relation "goose_db_version" does not exist at character 3612692026-09-21 12:56:58.854 UTC [681] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12702026/09/21 12:56:58 OK 20260628120000_add_object_size_and_stats.sql (2.34s)12712026/09/21 12:56:58 OK 20260905000000_add_claims.sql (23.08ms)12722026/09/21 12:56:58 OK 20260920000000_drop_claims.sql (33.68ms)12732026/09/21 12:56:58 goose: successfully migrated database to version: 2026092000000012742026/09/21 12:56:58 OK 1_commit_pending_closure.sql (46.56ms)12752026/09/21 12:56:59 OK 20251210153512_drop_unused_gin_index.sql (2.71s)12762026/09/21 12:56:59 OK 20251218171726_add_pins.sql (2.64s)12772026/09/21 12:56:59 OK 20251218171726_add_pins.sql (306.5ms)12782026/09/21 12:56:59 OK 20241026095416_initial_model.sql (1s)12792026/09/21 12:56:59 OK 2_object_stats_trigger.sql (993.23ms)12802026/09/21 12:57:00 goose: up to current file version: 21281=== NAME TestReadProxyNarinfoAlreadyDecompressed1282 proxy_test.go:180: unexpected error: context deadline exceeded1283 1284--- FAIL: TestReadProxyNarinfoAlreadyDecompressed (17.65s)1285=== CONT TestCreatePendingClosureRejectsOversizedNAR12862026/09/21 12:57:00 INFO Received uploads request method=POST path=/api/pending_closures1287--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)1288=== CONT TestUploadHandlersRejectOversizedBody1289=== NAME TestCompletedNarNotReofferedAcrossClosures1290 reupload_uncommitted_test.go:51: unexpected error: Put "http://localhost:38293/bucket7/nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=rustfsadmin%2F20260921%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20260921T125638Z&X-Amz-Expires=18000&X-Amz-SignedHeaders=host&partNumber=1&uploadId=MmVmZDRkZDctZjYwOS00NGQxLTlmYjItODU2MmMxYjBhNjJjLjE5NzZmYzYxLTRlZWQtNDVmMi1iNmRiLTI4YTBkNDZlNzBiNngxNzg5OTk1Mzk4MDM5Mzk1MjE3&X-Amz-Signature=1f44f2735bdd3c3db04928dbc94f893b4d3bc6529e40de958bc3d9312786f13a": context deadline exceeded1291 12922026/09/21 12:57:03 OK 20260628120000_add_object_size_and_stats.sql (3.98s)12932026/09/21 12:57:03 OK 20260628120000_add_object_size_and_stats.sql (3.76s)1294--- FAIL: TestCompletedNarNotReofferedAcrossClosures (31.17s)1295=== CONT TestCacheConfigHandlerMaxNarSize1296--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)1297=== CONT TestGenerateLandingPage12982026-09-21 12:57:03.444 UTC [682] ERROR: relation "goose_db_version" does not exist at character 3612992026-09-21 12:57:03.444 UTC [682] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1300--- PASS: TestGenerateLandingPage (0.02s)1301=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle13022026/09/21 12:57:03 OK 20260905000000_add_claims.sql (544.75ms)13032026/09/21 12:57:04 OK 20251210153512_drop_unused_gin_index.sql (3.95s)13042026/09/21 12:57:04 OK 20260905000000_add_claims.sql (986.16ms)13052026/09/21 12:57:04 OK 20260920000000_drop_claims.sql (427.65ms)13062026/09/21 12:57:04 goose: successfully migrated database to version: 2026092000000013072026/09/21 12:57:04 OK 20260920000000_drop_claims.sql (109.55ms)13082026/09/21 12:57:04 goose: successfully migrated database to version: 2026092000000013092026/09/21 12:57:04 OK 1_commit_pending_closure.sql (158.13ms)13102026/09/21 12:57:04 OK 20251218171726_add_pins.sql (222.62ms)13112026/09/21 12:57:04 OK 20241026095416_initial_model.sql (1.56s)13122026/09/21 12:57:04 OK 20241026095416_initial_model.sql (5.18s)13132026/09/21 12:57:04 OK 2_object_stats_trigger.sql (50.78ms)13142026/09/21 12:57:04 goose: up to current file version: 21315=== NAME TestReadProxyNarinfo1316 proxy_test.go:141: unexpected error: context deadline exceeded1317 1318--- FAIL: TestReadProxyNarinfo (21.60s)1319=== CONT TestIsValidUploadKey1320=== RUN TestIsValidUploadKey/narinfo1321=== PAUSE TestIsValidUploadKey/narinfo1322=== RUN TestIsValidUploadKey/nar_zst1323=== PAUSE TestIsValidUploadKey/nar_zst1324=== RUN TestIsValidUploadKey/nar_xz1325=== PAUSE TestIsValidUploadKey/nar_xz1326=== RUN TestIsValidUploadKey/nar_plain1327=== PAUSE TestIsValidUploadKey/nar_plain1328=== RUN TestIsValidUploadKey/listing1329=== PAUSE TestIsValidUploadKey/listing1330=== RUN TestIsValidUploadKey/build_log1331=== PAUSE TestIsValidUploadKey/build_log1332=== RUN TestIsValidUploadKey/build_log_home-manager_file1333=== PAUSE TestIsValidUploadKey/build_log_home-manager_file1334=== RUN TestIsValidUploadKey/build_log_plus_in_name1335=== PAUSE TestIsValidUploadKey/build_log_plus_in_name1336=== RUN TestIsValidUploadKey/build_log_question_mark1337=== PAUSE TestIsValidUploadKey/build_log_question_mark1338=== RUN TestIsValidUploadKey/build_log_equals1339=== PAUSE TestIsValidUploadKey/build_log_equals1340=== RUN TestIsValidUploadKey/realisation1341=== PAUSE TestIsValidUploadKey/realisation1342=== RUN TestIsValidUploadKey/realisation_plus_in_output1343=== PAUSE TestIsValidUploadKey/realisation_plus_in_output1344=== RUN TestIsValidUploadKey/nix-cache-info1345=== PAUSE TestIsValidUploadKey/nix-cache-info1346=== RUN TestIsValidUploadKey/index.html1347=== PAUSE TestIsValidUploadKey/index.html1348=== RUN TestIsValidUploadKey/narinfo_key,_nar_type1349=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type1350=== RUN TestIsValidUploadKey/nar_key,_narinfo_type1351=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type1352=== RUN TestIsValidUploadKey/listing_key,_narinfo_type1353=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type1354=== RUN TestIsValidUploadKey/traversal1355=== PAUSE TestIsValidUploadKey/traversal1356=== RUN TestIsValidUploadKey/traversal_nar1357=== PAUSE TestIsValidUploadKey/traversal_nar1358=== RUN TestIsValidUploadKey/absolute1359=== PAUSE TestIsValidUploadKey/absolute1360=== RUN TestIsValidUploadKey/empty_key1361=== PAUSE TestIsValidUploadKey/empty_key1362=== RUN TestIsValidUploadKey/unknown_type1363=== PAUSE TestIsValidUploadKey/unknown_type1364=== CONT TestProxyWriteTimeout1365=== RUN TestProxyWriteTimeout/narinfo1366=== PAUSE TestProxyWriteTimeout/narinfo1367=== RUN TestProxyWriteTimeout/1_GiB_nar1368=== PAUSE TestProxyWriteTimeout/1_GiB_nar1369=== RUN TestProxyWriteTimeout/10_GiB_nar1370=== PAUSE TestProxyWriteTimeout/10_GiB_nar1371=== RUN TestProxyWriteTimeout/unknown_size1372=== PAUSE TestProxyWriteTimeout/unknown_size1373=== CONT TestParseSize1374--- PASS: TestParseSize (0.00s)1375=== CONT TestService_Rustfstest13762026/09/21 12:57:04 OK 20260628120000_add_object_size_and_stats.sql (81.79ms)13772026/09/21 12:57:04 OK 20260905000000_add_claims.sql (28.52ms)13782026/09/21 12:57:04 OK 20260920000000_drop_claims.sql (34.32ms)13792026/09/21 12:57:04 goose: successfully migrated database to version: 2026092000000013802026/09/21 12:57:04 OK 1_commit_pending_closure.sql (95.52ms)13812026/09/21 12:57:04 OK 2_object_stats_trigger.sql (31.12ms)13822026/09/21 12:57:04 goose: up to current file version: 213832026-09-21 12:57:04.765 UTC [686] ERROR: relation "goose_db_version" does not exist at character 3613842026-09-21 12:57:04.765 UTC [686] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13852026/09/21 12:57:05 OK 1_commit_pending_closure.sql (1.23s)13862026/09/21 12:57:05 OK 20241026095416_initial_model.sql (373.4ms)13872026/09/21 12:57:05 OK 2_object_stats_trigger.sql (21.57ms)13882026/09/21 12:57:05 goose: up to current file version: 213892026-09-21 12:57:05.656 UTC [691] ERROR: relation "goose_db_version" does not exist at character 3613902026-09-21 12:57:05.656 UTC [691] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13912026-09-21 12:57:05.698 UTC [692] ERROR: relation "goose_db_version" does not exist at character 3613922026-09-21 12:57:05.698 UTC [692] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13932026-09-21 12:57:05.754 UTC [693] ERROR: relation "goose_db_version" does not exist at character 3613942026-09-21 12:57:05.754 UTC [693] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13952026-09-21 12:57:06.121 UTC [690] ERROR: relation "goose_db_version" does not exist at character 3613962026-09-21 12:57:06.121 UTC [690] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13972026-09-21 12:57:06.121 UTC [689] ERROR: relation "goose_db_version" does not exist at character 3613982026-09-21 12:57:06.121 UTC [689] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13992026/09/21 12:57:06 OK 20251210153512_drop_unused_gin_index.sql (705.73ms)14002026/09/21 12:57:06 OK 20241026095416_initial_model.sql (699.57ms)14012026-09-21 12:57:06.426 UTC [696] ERROR: relation "goose_db_version" does not exist at character 3614022026-09-21 12:57:06.426 UTC [696] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14032026/09/21 12:57:06 OK 20251210153512_drop_unused_gin_index.sql (1.7s)14042026/09/21 12:57:06 OK 20251210153512_drop_unused_gin_index.sql (395.03ms)14052026-09-21 12:57:06.492 UTC [697] ERROR: relation "goose_db_version" does not exist at character 3614062026-09-21 12:57:06.492 UTC [697] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14072026-09-21 12:57:06.515 UTC [694] ERROR: relation "goose_db_version" does not exist at character 3614082026-09-21 12:57:06.515 UTC [694] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14092026/09/21 12:57:06 OK 20251210153512_drop_unused_gin_index.sql (203.08ms)14102026-09-21 12:57:06.597 UTC [695] ERROR: relation "goose_db_version" does not exist at character 3614112026-09-21 12:57:06.597 UTC [695] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14122026/09/21 12:57:06 OK 20241026095416_initial_model.sql (3.71s)14132026/09/21 12:57:06 OK 20251218171726_add_pins.sql (62.78ms)14142026/09/21 12:57:06 OK 20251218171726_add_pins.sql (77.58ms)14152026/09/21 12:57:06 OK 20251218171726_add_pins.sql (364.45ms)14162026/09/21 12:57:06 OK 20241026095416_initial_model.sql (233.91ms)14172026/09/21 12:57:06 OK 20260628120000_add_object_size_and_stats.sql (180.19ms)14182026/09/21 12:57:06 OK 20241026095416_initial_model.sql (327.81ms)14192026/09/21 12:57:06 OK 20260628120000_add_object_size_and_stats.sql (204.17ms)14202026-09-21 12:57:06.853 UTC [698] ERROR: relation "goose_db_version" does not exist at character 3614212026-09-21 12:57:06.853 UTC [698] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14222026/09/21 12:57:06 OK 20260628120000_add_object_size_and_stats.sql (301.44ms)14232026/09/21 12:57:06 OK 20251210153512_drop_unused_gin_index.sql (81.3ms)14242026/09/21 12:57:06 OK 20260905000000_add_claims.sql (84.67ms)14252026-09-21 12:57:06.993 UTC [699] ERROR: relation "goose_db_version" does not exist at character 3614262026-09-21 12:57:06.993 UTC [699] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14272026/09/21 12:57:07 OK 20241026095416_initial_model.sql (356.04ms)14282026-09-21 12:57:07.007 UTC [700] ERROR: relation "goose_db_version" does not exist at character 3614292026-09-21 12:57:07.007 UTC [700] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC14302026/09/21 12:57:07 OK 20251218171726_add_pins.sql (65.73ms)14312026/09/21 12:57:07 OK 20260905000000_add_claims.sql (112.41ms)14322026/09/21 12:57:07 OK 20241026095416_initial_model.sql (301.43ms)14332026/09/21 12:57:07 OK 20260920000000_drop_claims.sql (78.37ms)14342026/09/21 12:57:07 goose: successfully migrated database to version: 2026092000000014352026/09/21 12:57:07 OK 20241026095416_initial_model.sql (386.55ms)14362026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (49.19ms)14372026/09/21 12:57:07 OK 20241026095416_initial_model.sql (204.56ms)14382026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (57.41ms)14392026/09/21 12:57:07 OK 20241026095416_initial_model.sql (216.72ms)14402026/09/21 12:57:07 OK 20260920000000_drop_claims.sql (63.98ms)14412026/09/21 12:57:07 goose: successfully migrated database to version: 2026092000000014422026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (70.78ms)14432026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (61.41ms)14442026/09/21 12:57:07 OK 1_commit_pending_closure.sql (77.32ms)14452026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (74.57ms)14462026/09/21 12:57:07 OK 20251218171726_add_pins.sql (76.67ms)14472026/09/21 12:57:07 OK 2_object_stats_trigger.sql (72.56ms)14482026/09/21 12:57:07 goose: up to current file version: 214492026/09/21 12:57:07 OK 20260905000000_add_claims.sql (128.02ms)14502026/09/21 12:57:07 OK 20251218171726_add_pins.sql (101.76ms)14512026/09/21 12:57:07 OK 20241026095416_initial_model.sql (210.08ms)14522026/09/21 12:57:07 OK 20251218171726_add_pins.sql (80.98ms)14532026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (71.5ms)14542026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (51.14ms)14552026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (83.62ms)14562026/09/21 12:57:07 OK 20251218171726_add_pins.sql (95.38ms)14572026/09/21 12:57:07 OK 20260920000000_drop_claims.sql (130.92ms)14582026/09/21 12:57:07 goose: successfully migrated database to version: 2026092000000014592026/09/21 12:57:07 OK 20241026095416_initial_model.sql (242.35ms)14602026/09/21 12:57:07 OK 20241026095416_initial_model.sql (997.49ms)14612026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (124.09ms)14622026/09/21 12:57:07 OK 20260905000000_add_claims.sql (125.3ms)14632026/09/21 12:57:07 OK 20251218171726_add_pins.sql (64.84ms)14642026/09/21 12:57:07 OK 1_commit_pending_closure.sql (66.41ms)14652026/09/21 12:57:07 OK 20260905000000_add_claims.sql (67.23ms)14662026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (70.34ms)14672026/09/21 12:57:07 OK 20260920000000_drop_claims.sql (82.27ms)14682026/09/21 12:57:07 goose: successfully migrated database to version: 2026092000000014692026/09/21 12:57:07 OK 2_object_stats_trigger.sql (26.33ms)14702026/09/21 12:57:07 goose: up to current file version: 214712026/09/21 12:57:07 OK 20260920000000_drop_claims.sql (46.45ms)14722026/09/21 12:57:07 goose: successfully migrated database to version: 2026092000000014732026/09/21 12:57:07 OK 1_commit_pending_closure.sql (36.99ms)14742026/09/21 12:57:07 OK 20251218171726_add_pins.sql (106.91ms)14752026/09/21 12:57:07 OK 1_commit_pending_closure.sql (58.82ms)14762026/09/21 12:57:07 OK 1_commit_pending_closure.sql (317.11ms)14772026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (652.26ms)14782026/09/21 12:57:07 OK 2_object_stats_trigger.sql (352.49ms)14792026/09/21 12:57:07 goose: up to current file version: 214802026/09/21 12:57:07 OK 2_object_stats_trigger.sql (378.33ms)14812026/09/21 12:57:07 goose: up to current file version: 214822026/09/21 12:57:07 OK 2_object_stats_trigger.sql (440.57ms)14832026/09/21 12:57:07 goose: up to current file version: 214842026/09/21 12:57:07 OK 20251210153512_drop_unused_gin_index.sql (593.46ms)14852026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (634.34ms)14862026/09/21 12:57:07 OK 20260628120000_add_object_size_and_stats.sql (585.59ms)14872026/09/21 12:57:07 OK 20260905000000_add_claims.sql (83.99ms)14882026/09/21 12:57:08 OK 20260905000000_add_claims.sql (40.56ms)14892026/09/21 12:57:08 OK 20260628120000_add_object_size_and_stats.sql (469.58ms)14902026/09/21 12:57:08 OK 20260905000000_add_claims.sql (44.83ms)14912026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (45.94ms)14922026/09/21 12:57:08 goose: successfully migrated database to version: 2026092000000014932026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (42.28ms)14942026/09/21 12:57:08 goose: successfully migrated database to version: 2026092000000014952026/09/21 12:57:08 OK 20251218171726_add_pins.sql (111.79ms)14962026/09/21 12:57:08 OK 20260905000000_add_claims.sql (58.16ms)14972026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (80.67ms)14982026/09/21 12:57:08 goose: successfully migrated database to version: 2026092000000014992026/09/21 12:57:08 OK 1_commit_pending_closure.sql (84.35ms)15002026/09/21 12:57:08 OK 2_object_stats_trigger.sql (13.87ms)15012026/09/21 12:57:08 goose: up to current file version: 215022026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (49.42ms)15032026/09/21 12:57:08 goose: successfully migrated database to version: 2026092000000015042026/09/21 12:57:08 OK 1_commit_pending_closure.sql (46.14ms)15052026/09/21 12:57:08 OK 2_object_stats_trigger.sql (12.69ms)15062026/09/21 12:57:08 goose: up to current file version: 215072026/09/21 12:57:08 OK 20260628120000_add_object_size_and_stats.sql (96.51ms)15082026/09/21 12:57:08 OK 1_commit_pending_closure.sql (61.49ms)15092026/09/21 12:57:08 OK 20241026095416_initial_model.sql (981.4ms)15102026/09/21 12:57:08 OK 2_object_stats_trigger.sql (63.59ms)15112026/09/21 12:57:08 goose: up to current file version: 215122026/09/21 12:57:08 OK 20260905000000_add_claims.sql (118.02ms)15132026/09/21 12:57:08 OK 20260905000000_add_claims.sql (1.49s)15142026/09/21 12:57:08 OK 1_commit_pending_closure.sql (271.86ms)15152026/09/21 12:57:08 OK 2_object_stats_trigger.sql (21.81ms)15162026/09/21 12:57:08 goose: up to current file version: 215172026/09/21 12:57:08 OK 20251210153512_drop_unused_gin_index.sql (1.6s)15182026/09/21 12:57:08 OK 20251210153512_drop_unused_gin_index.sql (296.25ms)15192026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (250.3ms)15202026/09/21 12:57:08 goose: successfully migrated database to version: 2026092000000015212026/09/21 12:57:08 OK 20251218171726_add_pins.sql (2.06s)15222026/09/21 12:57:08 OK 20260920000000_drop_claims.sql (267.48ms)15232026/09/21 12:57:08 goose: successfully migrated database to version: 202609200000001524{"timestamp":"2026-09-21T12:57:26.906676305Z","level":"ERROR","message":"data usage snapshot load failed","event":"data_usage_snapshot_load_failed","component":"ecstore","subsystem":"data_usage","state":"read_failed","operation":"remove_bucket_from_observed","reason":"other","object":"buckets/.usage.observed.json","error":"timeout","target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage/mod.rs","line_number":968,"threadName":"rustfs-worker","threadId":"ThreadId(209)"}1525=== NAME TestObjectStatsTrigger1526 object_stats_test.go:16: unexpected error: Put "http://localhost:38293/bucket32/": context deadline exceeded1527 1528=== NAME TestService_ReadAuthMiddleware1529 auth_test.go:194: unexpected error: context deadline exceeded1530 1531=== NAME TestCompleteMultipartUnregistered1532 uploads_test.go:441: unexpected error: context deadline exceeded1533 1534=== NAME TestCacheStatsHandler1535 cache_stats_test.go:17: unexpected error: context deadline exceeded1536 1537--- FAIL: TestObjectStatsTrigger (43.96s)1538=== CONT TestSkippedUploadsHandler1539=== NAME TestService_AuthMiddleware_MTLSBoundSubjects1540 auth_test.go:116: unexpected error: context deadline exceeded1541 1542=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure1543=== NAME TestCreatePendingClosure_SmallNARUsesSimplePUT1544 uploads_test.go:468: unexpected error: context deadline exceeded1545 1546=== NAME TestOrphanedObjectsGCStressTest1547 orphaned_objects_gc_test.go:307: unexpected error: context deadline exceeded1548 1549--- FAIL: TestService_ReadAuthMiddleware (43.99s)1550--- FAIL: TestCompleteMultipartUnregistered (43.84s)1551=== CONT TestResolveDBConnectionString/PGHOST_allows_empty1552--- FAIL: TestCacheStatsHandler (44.40s)1553=== CONT TestResolveDBConnectionString/file_when_flag_empty1554=== CONT TestResolveDBConnectionString/flag_wins1555=== CONT TestResolveDBConnectionString/missing_file_is_an_error1556=== NAME TestService_RequireScope_OIDC1557 auth_test.go:368: unexpected error: context deadline exceeded1558 15592026/09/21 12:57:26 OK 20260628120000_add_object_size_and_stats.sql (18.4s)1560=== NAME TestService_verifyS3Integrity1561 uploads_test.go:383: unexpected error: context deadline exceeded1562 1563=== NAME TestOrphanedObjectsGC1564 orphaned_objects_gc_test.go:98: unexpected error: context deadline exceeded1565 1566=== NAME TestService_createPendingClosureHandler1567 uploads_test.go:247: unexpected error: context deadline exceeded1568 1569=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure15702026/09/21 12:57:26 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"1571--- FAIL: TestCreatePendingClosure_SmallNARUsesSimplePUT (43.85s)1572=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart15732026/09/21 12:57:26 OK 20251218171726_add_pins.sql (18.41s)1574=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart1575--- FAIL: TestOrphanedObjectsGCStressTest (43.99s)15762026/09/21 12:57:26 INFO Client skipped oversized paths paths=3 nar_bytes=50000000001577--- FAIL: TestService_AuthMiddleware_MTLSBoundSubjects (43.97s)1578=== CONT TestServerTLSConfig/no_client_CA1579=== CONT TestClientErrorHandling/ServerNotAvailable1580--- FAIL: TestService_verifyS3Integrity (41.29s)1581=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1582--- FAIL: TestOrphanedObjectsGC (43.97s)1583=== CONT TestCacheConfigHandler/no_signing_keys1584=== CONT TestServerTLSConfig/not_a_PEM_file1585=== CONT TestServerTLSConfig/missing_CA_file1586=== CONT TestIsValidCachePath/narinfo1587=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1588--- FAIL: TestService_RequireScope_OIDC (44.09s)15892026/09/21 12:57:26 INFO Received uploads request method=POST path=/1590--- PASS: TestServerTLSConfig (0.05s)1591 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1592 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1593 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1594=== CONT TestResolveDBConnectionString/nothing_configured1595=== CONT TestIsValidCachePath/wrong_extension15962026/09/21 12:57:26 OK 20251218171726_add_pins.sql (18.42s)1597=== CONT TestIsValidCachePath/invalid_char_u1598=== CONT TestClientErrorHandling/InvalidAuthToken1599=== CONT TestCacheConfigHandler/full_config,_no_issuer1600=== CONT TestClientErrorHandling/InvalidStorePath1601=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts1602=== CONT TestCacheConfigHandler/no_cache_url_configured1603=== CONT TestIsValidCachePath/short_hash1604=== CONT TestIsValidCachePath/invalid_char_e1605--- FAIL: TestService_createPendingClosureHandler (38.08s)1606=== CONT TestIsValidCachePath/leading_slash1607=== CONT TestIsValidCachePath/empty1608=== CONT TestIsValidCachePath/random_path1609=== CONT TestIsValidCachePath/traversal_parent1610=== CONT TestIsValidCachePath/nix-cache-info1611=== CONT TestIsValidCachePath/traversal_in_middle1612=== CONT TestIsValidCachePath/log1613=== CONT TestIsValidCachePath/ls1614=== CONT TestIsValidCachePath/index.html1615=== CONT TestIsValidCachePath/realisation1616=== CONT TestIsValidCachePath/nar_uncompressed16172026/09/21 12:57:26 OK 1_commit_pending_closure.sql (10.39ms)1618--- PASS: TestCacheConfigHandler (0.25s)1619 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1620 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1621 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1622 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1623=== CONT TestIsValidCachePath/nar_zst1624=== CONT TestParseSingleRange/none1625=== CONT TestIsValidCachePath/nar_bz21626=== CONT TestParseSingleRange/start_far_past_EOF1627=== CONT TestParseSingleRange/start_past_EOF1628=== CONT TestParseSingleRange/single_byte16292026/09/21 12:57:26 OK 20251210153512_drop_unused_gin_index.sql (10.78ms)1630=== CONT TestParseSingleRange/suffix_exceeds_size1631=== CONT TestParseSingleRange/suffix1632=== CONT TestParseSingleRange/end_clamped_to_size1633=== CONT TestParseSingleRange/malformed_both_empty1634=== CONT TestParseSingleRange/open-ended16352026/09/21 12:57:26 OK 20251218171726_add_pins.sql (19.9s)1636=== CONT TestParseSingleRange/malformed_end_before_start1637=== CONT TestParseSingleRange/multi-range_ignored1638=== CONT TestParseSingleRange/malformed_no_dash16392026/09/21 12:57:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1640=== CONT TestIsValidCachePath/nar_xz16412026-09-21 12:57:26.958 UTC [708] ERROR: relation "goose_db_version" does not exist at character 3616422026-09-21 12:57:26.958 UTC [708] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC16432026-09-21 12:57:26.958 UTC [707] ERROR: relation "goose_db_version" does not exist at character 3616442026-09-21 12:57:26.958 UTC [707] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1645=== CONT TestIsValidUploadKey/narinfo16462026/09/21 12:57:26 WARN Failed to register uploaded object key=v0659lqmzi1i89898kp1ms0fwjdiwsax.ls error="server returned 404: 404 page not found\n"16472026-09-21 12:57:26.958 UTC [709] ERROR: relation "goose_db_version" does not exist at character 3616482026-09-21 12:57:26.958 UTC [709] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1649=== CONT TestParseSingleRange/closed1650=== CONT TestIsValidUploadKey/unknown_type1651=== CONT TestParseSingleRange/unknown_unit1652=== CONT TestIsValidUploadKey/absolute1653=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars1654=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1655=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1656=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1657=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts1658=== CONT TestIsValidUploadKey/realisation_plus_in_output1659=== CONT TestIsValidUploadKey/listing_key,_narinfo_type1660=== CONT TestIsValidUploadKey/nar_key,_narinfo_type16612026/09/21 12:57:26 INFO Signed narinfos id=2 count=11662=== CONT TestIsValidUploadKey/index.html16632026/09/21 12:57:26 INFO Received request for more parts method=POST path=/1664=== CONT TestIsValidUploadKey/nix-cache-info1665=== CONT TestIsValidUploadKey/build_log_home-manager_file16662026/09/21 12:57:26 INFO Uploading 1 narinfos1667=== CONT TestIsValidUploadKey/realisation1668=== CONT TestIsValidUploadKey/build_log_question_mark16692026/09/21 12:57:26 INFO Received uploads request method=POST path=/1670=== CONT TestIsValidUploadKey/build_log_plus_in_name1671=== CONT TestIsValidUploadKey/nar_plain1672--- PASS: TestResolveDBConnectionString (0.01s)1673 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)1674 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)1675 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)1676 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)1677 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)16782026/09/21 12:57:26 INFO Received complete multipart upload request method=POST path=/1679--- PASS: TestParseSingleRange (0.26s)1680 --- PASS: TestParseSingleRange/none (0.00s)1681 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1682 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1683 --- PASS: TestParseSingleRange/single_byte (0.00s)1684 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1685 --- PASS: TestParseSingleRange/suffix (0.00s)1686 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1687 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1688 --- PASS: TestParseSingleRange/open-ended (0.00s)1689 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1690 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1691 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1692 --- PASS: TestParseSingleRange/closed (0.00s)1693 --- PASS: TestParseSingleRange/unknown_unit (0.00s)1694=== CONT TestIsValidUploadKey/build_log_equals1695=== CONT TestIsValidUploadKey/nar_xz1696=== CONT TestIsValidUploadKey/nar_zst1697=== CONT TestProxyWriteTimeout/narinfo1698=== CONT TestProxyWriteTimeout/unknown_size1699=== CONT TestProxyWriteTimeout/10_GiB_nar1700=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure17012026/09/21 12:57:26 INFO Received uploads request method=POST path=/1702=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts17032026/09/21 12:57:26 INFO Received request for more parts method=POST path=/17042026/09/21 12:57:26 OK 20260905000000_add_claims.sql (13.14ms)1705=== CONT TestIsValidUploadKey/traversal_nar1706=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart17072026/09/21 12:57:26 INFO Received complete multipart upload request method=POST path=/1708=== CONT TestIsValidUploadKey/traversal1709=== CONT TestIsValidUploadKey/narinfo_key,_nar_type1710=== CONT TestIsValidUploadKey/listing1711=== CONT TestIsValidUploadKey/build_log1712--- PASS: TestIsValidCachePath (0.27s)1713 --- PASS: TestIsValidCachePath/narinfo (0.00s)1714 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1715 --- PASS: TestIsValidCachePath/short_hash (0.00s)1716 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1717 --- PASS: TestIsValidCachePath/leading_slash (0.00s)1718 --- PASS: TestIsValidCachePath/empty (0.00s)1719 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1720 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1721 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1722 --- PASS: TestIsValidCachePath/log (0.00s)1723 --- PASS: TestIsValidCachePath/ls (0.00s)1724 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1725 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1726 --- PASS: TestIsValidCachePath/index.html (0.00s)1727 --- PASS: TestIsValidCachePath/realisation (0.00s)1728 --- PASS: TestIsValidCachePath/random_path (0.01s)1729 --- PASS: TestIsValidCachePath/nar_zst (0.00s)1730 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1731 --- PASS: TestIsValidCachePath/nar_xz (0.00s)1732 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1733=== CONT TestIsValidUploadKey/empty_key1734=== CONT TestProxyWriteTimeout/1_GiB_nar1735--- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)1736 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1737 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1738 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1739 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1740--- PASS: TestSkippedUploadsHandler (0.01s)1741--- PASS: TestIsValidUploadKey (0.00s)1742 --- PASS: TestIsValidUploadKey/narinfo (0.00s)1743 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1744 --- PASS: TestIsValidUploadKey/absolute (0.00s)1745 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1746 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1747 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1748 --- PASS: TestIsValidUploadKey/index.html (0.00s)1749 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1750 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1751 --- PASS: TestIsValidUploadKey/realisation (0.00s)1752 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1753 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1754 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1755 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1756 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1757 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1758 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1759 --- PASS: TestIsValidUploadKey/traversal (0.00s)1760 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1761 --- PASS: TestIsValidUploadKey/listing (0.00s)1762 --- PASS: TestIsValidUploadKey/build_log (0.00s)1763 --- PASS: TestIsValidUploadKey/empty_key (0.00s)1764--- PASS: TestProxyWriteTimeout (0.00s)1765 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1766 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1767 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1768 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)17692026/09/21 12:57:26 OK 2_object_stats_trigger.sql (5.39ms)17702026/09/21 12:57:26 goose: up to current file version: 217712026/09/21 12:57:26 OK 20260628120000_add_object_size_and_stats.sql (14.68ms)1772=== NAME TestResurrectedObjectNotDeleted1773 orphaned_objects_gc_test.go:533: unexpected error: context deadline exceeded1774 1775--- FAIL: TestResurrectedObjectNotDeleted (44.10s)17762026/09/21 12:57:26 OK 20251218171726_add_pins.sql (5.81ms)17772026/09/21 12:57:26 OK 1_commit_pending_closure.sql (5.71ms)17782026/09/21 12:57:26 OK 20260920000000_drop_claims.sql (3.6ms)17792026/09/21 12:57:26 goose: successfully migrated database to version: 2026092000000017802026/09/21 12:57:26 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete17812026/09/21 12:57:26 WARN Failed to register uploaded object key=v0659lqmzi1i89898kp1ms0fwjdiwsax.narinfo error="server returned 404: 404 page not found\n"17822026/09/21 12:57:26 OK 20260628120000_add_object_size_and_stats.sql (17.44ms)17832026/09/21 12:57:26 OK 2_object_stats_trigger.sql (6.75ms)17842026/09/21 12:57:26 goose: up to current file version: 217852026/09/21 12:57:26 OK 20260905000000_add_claims.sql (7.81ms)1786=== NAME TestMultipartCleanup1787 multipart_cleanup_test.go:14: unexpected error: context deadline exceeded1788 1789--- FAIL: TestMultipartCleanup (43.99s)17902026/09/21 12:57:26 OK 1_commit_pending_closure.sql (7.98ms)17912026/09/21 12:57:26 OK 20241026095416_initial_model.sql (14.84ms)17922026/09/21 12:57:26 INFO Completed upload id=217932026/09/21 12:57:26 OK 20260628120000_add_object_size_and_stats.sql (14.23ms)17942026/09/21 12:57:26 INFO Upload complete. (46.807s)17952026/09/21 12:57:26 OK 20260628120000_add_object_size_and_stats.sql (8.62ms)17962026/09/21 12:57:26 OK 20260905000000_add_claims.sql (9.92ms)17972026/09/21 12:57:26 OK 2_object_stats_trigger.sql (5.14ms)17982026/09/21 12:57:26 goose: up to current file version: 21799=== NAME TestService_AuthMiddleware_OIDC1800 auth_test.go:269: unexpected error: context deadline exceeded1801 1802--- FAIL: TestService_AuthMiddleware_OIDC (44.05s)18032026/09/21 12:57:26 OK 20241026095416_initial_model.sql (15.28ms)18042026/09/21 12:57:26 OK 20260905000000_add_claims.sql (7.74ms)18052026/09/21 12:57:26 OK 20260905000000_add_claims.sql (8.12ms)18062026/09/21 12:57:26 OK 20241026095416_initial_model.sql (14.85ms)18072026/09/21 12:57:26 OK 20251210153512_drop_unused_gin_index.sql (8.7ms)18082026/09/21 12:57:26 OK 20260920000000_drop_claims.sql (4.13ms)18092026/09/21 12:57:26 goose: successfully migrated database to version: 2026092000000018102026/09/21 12:57:26 OK 20260920000000_drop_claims.sql (7.74ms)18112026/09/21 12:57:26 goose: successfully migrated database to version: 2026092000000018122026/09/21 12:57:26 OK 20241026095416_initial_model.sql (13.33ms)18132026/09/21 12:57:26 OK 20251210153512_drop_unused_gin_index.sql (6.49ms)18142026/09/21 12:57:26 OK 20260920000000_drop_claims.sql (7.43ms)18152026/09/21 12:57:26 goose: successfully migrated database to version: 2026092000000018162026/09/21 12:57:26 OK 20251210153512_drop_unused_gin_index.sql (8.19ms)18172026/09/21 12:57:26 OK 20260920000000_drop_claims.sql (8.15ms)18182026/09/21 12:57:26 goose: successfully migrated database to version: 2026092000000018192026/09/21 12:57:26 OK 1_commit_pending_closure.sql (9.21ms)18202026/09/21 12:57:26 OK 1_commit_pending_closure.sql (9ms)18212026/09/21 12:57:26 OK 20251210153512_drop_unused_gin_index.sql (6.37ms)18222026/09/21 12:57:26 OK 1_commit_pending_closure.sql (5.21ms)18232026/09/21 12:57:26 OK 20251218171726_add_pins.sql (12.13ms)18242026/09/21 12:57:26 OK 2_object_stats_trigger.sql (6.24ms)18252026/09/21 12:57:26 OK 20251218171726_add_pins.sql (9.32ms)18262026/09/21 12:57:26 goose: up to current file version: 218272026/09/21 12:57:26 OK 2_object_stats_trigger.sql (6.23ms)1828=== NAME TestService_cleanupPendingClosuresHandler18292026/09/21 12:57:26 goose: up to current file version: 21830 uploads_test.go:108: unexpected error: context deadline exceeded1831 1832--- FAIL: TestService_cleanupPendingClosuresHandler (36.53s)18332026/09/21 12:57:26 OK 1_commit_pending_closure.sql (8.05ms)1834=== NAME TestService_AuthMiddleware_MTLSProxyHeader18352026/09/21 12:57:26 OK 20251218171726_add_pins.sql (8.59ms)1836 auth_test.go:55: unexpected error: context deadline exceeded1837 1838--- FAIL: TestService_AuthMiddleware_MTLSProxyHeader (44.02s)18392026/09/21 12:57:27 OK 20251218171726_add_pins.sql (8.76ms)18402026/09/21 12:57:27 OK 2_object_stats_trigger.sql (8.54ms)18412026/09/21 12:57:27 goose: up to current file version: 218422026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (8.54ms)1843=== NAME TestNARDeduplicationMetadataUploadBug1844 metadata_upload_test.go:25: unexpected error: context deadline exceeded1845 1846--- FAIL: TestNARDeduplicationMetadataUploadBug (40.41s)18472026/09/21 12:57:27 OK 2_object_stats_trigger.sql (4.59ms)18482026/09/21 12:57:27 goose: up to current file version: 218492026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (5.48ms)1850=== NAME TestService_NativeMTLS1851 mtls_test.go:22: unexpected error: context deadline exceeded18522026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (5.93ms)1853 1854--- FAIL: TestService_NativeMTLS (43.92s)18552026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (4.66ms)18562026/09/21 12:57:27 OK 20260905000000_add_claims.sql (6.05ms)18572026/09/21 12:57:27 OK 20260905000000_add_claims.sql (6.37ms)18582026/09/21 12:57:27 OK 20260905000000_add_claims.sql (4.93ms)18592026/09/21 12:57:27 OK 20260905000000_add_claims.sql (6.48ms)18602026/09/21 12:57:27 INFO Received create pin request method=POST path=/api/pins/myapp18612026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (3.21ms)18622026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000018632026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (3.85ms)18642026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000018652026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (5.79ms)18662026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000018672026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (6.26ms)18682026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000018692026/09/21 12:57:27 OK 1_commit_pending_closure.sql (7.02ms)18702026/09/21 12:57:27 OK 1_commit_pending_closure.sql (5.39ms)18712026/09/21 12:57:27 OK 1_commit_pending_closure.sql (7.93ms)18722026/09/21 12:57:27 OK 1_commit_pending_closure.sql (7.81ms)18732026/09/21 12:57:27 OK 2_object_stats_trigger.sql (5.94ms)18742026/09/21 12:57:27 goose: up to current file version: 218752026/09/21 12:57:27 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC1502258940/001/store/wjydvvqp87jwx6jk92wi8a6fqdzmzdb8-pinned-file.txt narinfo_key=wjydvvqp87jwx6jk92wi8a6fqdzmzdb8.narinfo1876=== NAME TestService_ReadScope_PublicByDefault1877 auth_test.go:428: unexpected error: context deadline exceeded1878 1879--- FAIL: TestService_ReadScope_PublicByDefault (44.18s)18802026/09/21 12:57:27 OK 2_object_stats_trigger.sql (6.19ms)18812026/09/21 12:57:27 goose: up to current file version: 218822026/09/21 12:57:27 OK 2_object_stats_trigger.sql (5.97ms)18832026/09/21 12:57:27 goose: up to current file version: 218842026/09/21 12:57:27 OK 2_object_stats_trigger.sql (5.04ms)18852026/09/21 12:57:27 goose: up to current file version: 218862026/09/21 12:57:27 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present18872026-09-21 12:57:27.044 UTC [767] ERROR: relation "goose_db_version" does not exist at character 3618882026-09-21 12:57:27.044 UTC [767] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC18892026-09-21 12:57:27.048 UTC [768] ERROR: relation "goose_db_version" does not exist at character 3618902026-09-21 12:57:27.048 UTC [768] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC18912026/09/21 12:57:27 OK 20241026095416_initial_model.sql (9.29ms)18922026/09/21 12:57:27 OK 20251210153512_drop_unused_gin_index.sql (2.59ms)18932026/09/21 12:57:27 OK 20241026095416_initial_model.sql (11.65ms)18942026/09/21 12:57:27 OK 20251218171726_add_pins.sql (6.87ms)18952026/09/21 12:57:27 OK 20251210153512_drop_unused_gin_index.sql (2.79ms)18962026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (3.43ms)18972026/09/21 12:57:27 OK 20251218171726_add_pins.sql (6.69ms)18982026/09/21 12:57:27 OK 20260905000000_add_claims.sql (6.1ms)18992026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (2.12ms)19002026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000019012026/09/21 12:57:27 OK 20260628120000_add_object_size_and_stats.sql (3.69ms)19022026/09/21 12:57:27 OK 1_commit_pending_closure.sql (2.35ms)19032026/09/21 12:57:27 OK 2_object_stats_trigger.sql (1.22ms)19042026/09/21 12:57:27 goose: up to current file version: 219052026/09/21 12:57:27 OK 20260905000000_add_claims.sql (4.16ms)19062026/09/21 12:57:27 OK 20260920000000_drop_claims.sql (2.1ms)19072026/09/21 12:57:27 goose: successfully migrated database to version: 2026092000000019082026/09/21 12:57:27 OK 1_commit_pending_closure.sql (2.36ms)19092026/09/21 12:57:27 OK 2_object_stats_trigger.sql (930.57µs)19102026/09/21 12:57:27 goose: up to current file version: 219112026/09/21 12:57:27 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=218.921305ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19122026/09/21 12:57:27 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=384.289316ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19132026/09/21 12:57:27 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=721.073241ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1914--- PASS: TestUploadHandlersRejectOversizedBody (26.78s)1915 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.04s)1916 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.06s)1917 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.00s)19182026/09/21 12:57:28 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.547388875s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19192026/09/21 12:57:29 INFO Starting cleanup of old closures method=DELETE path=/api/closures19202026/09/21 12:57:29 INFO Garbage collection started19212026/09/21 12:57:29 INFO Aborted multipart uploads count=019222026/09/21 12:57:29 WARN Force mode enabled - objects will be deleted immediately without grace period1923--- PASS: TestService_Rustfstest (24.90s)1924--- PASS: TestMetricsInventory (46.32s)19252026/09/21 12:57:29 INFO Received uploads request method=POST path=/api/pending_closures19262026/09/21 12:57:30 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19272026/09/21 12:57:30 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=207.457124ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19282026/09/21 12:57:30 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=385.203684ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19292026/09/21 12:57:30 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=815.900961ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19302026/09/21 12:57:31 INFO Garbage collection progress phase=cleanup_orphan_objects failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=0 objects_failed=019312026/09/21 12:57:31 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.533687412s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19322026/09/21 12:57:33 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: sending request: request failed after retries: Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused"19332026/09/21 12:57:33 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19342026/09/21 12:57:33 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=187.79465ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1935=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1936 throttle_test.go:163: unexpected error: Put "http://localhost:38293/bucket53/nar/bbbbbbbbbbbbbbbbbbbbbbbbbbbbbb0100000000000000000000.nar.zst?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=rustfsadmin%2F20260921%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20260921T125729Z&X-Amz-Expires=18000&X-Amz-SignedHeaders=host&partNumber=1&uploadId=MmVmZDRkZDctZjYwOS00NGQxLTlmYjItODU2MmMxYjBhNjJjLjFlNDNjYWE3LWM1Y2UtNGFiOC1iYTg2LTFmOWE0YjdlYjYxY3gxNzg5OTk1NDQ5NDk2NjY5NTk1&X-Amz-Signature=eb7e6ece6728fee02dc482cfa95585d9ffb24f093b59dd42b6f56bc646859100": context deadline exceeded1937 1938--- FAIL: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (30.00s)19392026/09/21 12:57:33 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=427.615146ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19402026/09/21 12:57:33 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19412026/09/21 12:57:33 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"19422026/09/21 12:57:33 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19432026/09/21 12:57:33 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=749.940516ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19442026/09/21 12:57:34 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.755495213s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1945--- PASS: TestClientErrorHandling (0.00s)1946 --- PASS: TestClientErrorHandling/InvalidStorePath (6.81s)1947 --- PASS: TestClientErrorHandling/InvalidAuthToken (6.87s)1948 --- PASS: TestClientErrorHandling/ServerNotAvailable (9.45s)19492026/09/21 12:57:59 INFO Garbage collection progress phase=cleanup_orphan_objects failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=1000 objects_failed=019502026/09/21 12:58:02 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=019512026/09/21 12:58:02 INFO Vacuumed table table=pending_closures19522026/09/21 12:58:02 INFO Vacuumed table table=pending_objects19532026/09/21 12:58:02 INFO Vacuumed table table=multipart_uploads19542026/09/21 12:58:02 INFO Vacuumed table table=closures19552026/09/21 12:58:02 INFO Vacuumed table table=objects19562026/09/21 12:58:03 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01957=== NAME TestPinProtectsFromGC1958 client_integration_test.go:794: Pin successfully protected closure from garbage collection1959--- PASS: TestPinProtectsFromGC (91.13s)1960FAIL19612026-09-21 12:58:03.492 UTC [129] LOG: received smart shutdown request19622026/09/21 12:58:13 ERROR failed to kill rustfs error="no such process"19632026/09/21 12:58:13 INFO killed rustfs19642026-09-21 12:58:13.496 UTC [698] FATAL: terminating connection due to unexpected postmaster exit19652026-09-21 12:58:13.496 UTC [694] FATAL: terminating connection due to unexpected postmaster exit19662026-09-21 12:58:13.496 UTC [695] FATAL: terminating connection due to unexpected postmaster exit19672026-09-21 12:58:13.496 UTC [696] FATAL: terminating connection due to unexpected postmaster exit19682026-09-21 12:58:13.496 UTC [697] FATAL: terminating connection due to unexpected postmaster exit19692026-09-21 12:58:13.496 UTC [699] FATAL: terminating connection due to unexpected postmaster exit19702026-09-21 12:58:13.496 UTC [693] FATAL: terminating connection due to unexpected postmaster exit19712026-09-21 12:58:13.496 UTC [700] FATAL: terminating connection due to unexpected postmaster exit19722026-09-21 12:58:13.496 UTC [692] FATAL: terminating connection due to unexpected postmaster exit19732026-09-21 12:58:13.496 UTC [691] FATAL: terminating connection due to unexpected postmaster exit19742026-09-21 12:58:13.496 UTC [689] FATAL: terminating connection due to unexpected postmaster exit19752026-09-21 12:58:13.496 UTC [686] FATAL: terminating connection due to unexpected postmaster exit19762026-09-21 12:58:13.496 UTC [690] FATAL: terminating connection due to unexpected postmaster exit19772026-09-21 12:58:13.496 UTC [681] FATAL: terminating connection due to unexpected postmaster exit19782026-09-21 12:58:13.496 UTC [682] FATAL: terminating connection due to unexpected postmaster exit19792026-09-21 12:58:13.496 UTC [680] FATAL: terminating connection due to unexpected postmaster exit19802026-09-21 12:58:13.496 UTC [679] FATAL: terminating connection due to unexpected postmaster exit19812026-09-21 12:58:13.496 UTC [678] FATAL: terminating connection due to unexpected postmaster exit19822026-09-21 12:58:13.496 UTC [675] FATAL: terminating connection due to unexpected postmaster exit19832026-09-21 12:58:13.496 UTC [673] FATAL: terminating connection due to unexpected postmaster exit19842026-09-21 12:58:13.496 UTC [671] FATAL: terminating connection due to unexpected postmaster exit19852026-09-21 12:58:13.496 UTC [570] FATAL: terminating connection due to unexpected postmaster exit19862026-09-21 12:58:13.496 UTC [576] FATAL: terminating connection due to unexpected postmaster exit19872026-09-21 12:58:13.496 UTC [595] FATAL: terminating connection due to unexpected postmaster exit19882026-09-21 12:58:13.496 UTC [476] FATAL: terminating connection due to unexpected postmaster exit19892026-09-21 12:58:13.496 UTC [472] FATAL: terminating connection due to unexpected postmaster exit19902026-09-21 12:58:13.496 UTC [473] FATAL: terminating connection due to unexpected postmaster exit19912026-09-21 12:58:13.496 UTC [471] FATAL: terminating connection due to unexpected postmaster exit19922026-09-21 12:58:13.496 UTC [470] FATAL: terminating connection due to unexpected postmaster exit19932026-09-21 12:58:13.496 UTC [469] FATAL: terminating connection due to unexpected postmaster exit19942026-09-21 12:58:13.496 UTC [468] FATAL: terminating connection due to unexpected postmaster exit19952026-09-21 12:58:13.496 UTC [460] FATAL: terminating connection due to unexpected postmaster exit19962026-09-21 12:58:13.496 UTC [459] FATAL: terminating connection due to unexpected postmaster exit19972026-09-21 12:58:13.496 UTC [457] FATAL: terminating connection due to unexpected postmaster exit19982026-09-21 12:58:13.496 UTC [360] FATAL: terminating connection due to unexpected postmaster exit19992026-09-21 12:58:13.496 UTC [467] FATAL: terminating connection due to unexpected postmaster exit20002026-09-21 12:58:13.500 UTC [466] FATAL: terminating connection due to unexpected postmaster exit20012026-09-21 12:58:13.500 UTC [464] FATAL: terminating connection due to unexpected postmaster exit20022026-09-21 12:58:13.501 UTC [465] FATAL: terminating connection due to unexpected postmaster exit20032026-09-21 12:58:13.501 UTC [463] FATAL: terminating connection due to unexpected postmaster exit20042026-09-21 12:58:13.502 UTC [462] FATAL: terminating connection due to unexpected postmaster exit20052026-09-21 12:58:13.502 UTC [461] FATAL: terminating connection due to unexpected postmaster exit20062026-09-21 12:58:13.502 UTC [458] FATAL: terminating connection due to unexpected postmaster exit20072026-09-21 12:58:13.502 UTC [456] FATAL: terminating connection due to unexpected postmaster exit20082026/09/21 12:58:13 ERROR failed to wait for rustfs error="signal: killed"