niks3-go-unit-tests log

succeeded `niks3-go-unit-tests` `aarch64-linux.go-unit-tests` · build #94 · raw

1tribuchet: building on eliza2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestCaseHackSuffix6=== PAUSE TestCaseHackSuffix7=== RUN TestPartSizeForNAR8=== PAUSE TestPartSizeForNAR9=== RUN TestUploadMultipart_SupersededByPeer10=== PAUSE TestUploadMultipart_SupersededByPeer11=== RUN TestDumpPathMatchesNix12=== PAUSE TestDumpPathMatchesNix13=== RUN TestDumpPathSingleFile14=== PAUSE TestDumpPathSingleFile15=== RUN TestDumpPathWriterError16=== PAUSE TestDumpPathWriterError17=== RUN TestEncodeNixBase3218=== PAUSE TestEncodeNixBase3219=== RUN TestEncodeNixBase32WithRealHash20=== PAUSE TestEncodeNixBase32WithRealHash21=== RUN TestConvertHashToNix3222=== PAUSE TestConvertHashToNix3223=== RUN TestGetStorePathHash24=== PAUSE TestGetStorePathHash25=== RUN TestPathInfoHashCompatibility26=== PAUSE TestPathInfoHashCompatibility27=== RUN TestParsePathInfoJSON28=== PAUSE TestParsePathInfoJSON29=== RUN TestParsePathInfoJSONMultiplePaths30=== PAUSE TestParsePathInfoJSONMultiplePaths31=== RUN TestPathInfoCACompatibility32=== PAUSE TestPathInfoCACompatibility33=== RUN TestRateLimiterFeedback34=== PAUSE TestRateLimiterFeedback35=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess36=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess37=== RUN TestResolveStorePath38=== PAUSE TestResolveStorePath39=== RUN TestDoWithRetry_BodyReplayedViaGetBody40=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody41=== RUN TestShellSplit42=== PAUSE TestShellSplit43=== RUN TestShellSplitErrors44=== PAUSE TestShellSplitErrors45=== RUN TestSetClientTLS46=== PAUSE TestSetClientTLS47=== RUN TestSetClientTLSDoesNotMutateDefaultTransport48=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport49=== RUN TestSetClientTLSErrors50=== PAUSE TestSetClientTLSErrors51=== RUN TestStaticToken52=== PAUSE TestStaticToken53=== RUN TestFileTokenReadsAndCaches54=== PAUSE TestFileTokenReadsAndCaches55=== RUN TestFileTokenMissing56=== PAUSE TestFileTokenMissing57=== RUN TestFileTokenEmpty58=== PAUSE TestFileTokenEmpty59=== RUN TestScriptTokenNoExpiryRerunsEveryCall60=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall61=== RUN TestScriptTokenCachesUntilRefresh62=== PAUSE TestScriptTokenCachesUntilRefresh63=== RUN TestScriptTokenEmptyToken64=== PAUSE TestScriptTokenEmptyToken65=== RUN TestScriptTokenBadJSON66=== PAUSE TestScriptTokenBadJSON67=== RUN TestScriptTokenScriptFails68=== PAUSE TestScriptTokenScriptFails69=== RUN TestScriptTokenEmptyCommand70=== PAUSE TestScriptTokenEmptyCommand71=== CONT TestDoServerRequestAttachesToken72=== CONT TestResolveStorePath73=== CONT TestScriptTokenEmptyCommand74=== CONT TestFileTokenReadsAndCaches75--- PASS: TestScriptTokenEmptyCommand (0.00s)76=== CONT TestShellSplit77=== CONT TestScriptTokenCachesUntilRefresh78=== CONT TestScriptTokenScriptFails79--- PASS: TestShellSplit (0.00s)80=== CONT TestScriptTokenBadJSON81=== CONT TestStaticToken82--- PASS: TestStaticToken (0.00s)83=== CONT TestConvertHashToNix3284=== RUN TestConvertHashToNix32/SRI_format_to_Nix3285=== CONT TestSetClientTLSErrors86=== CONT TestSetClientTLSDoesNotMutateDefaultTransport87=== CONT TestSetClientTLS88=== CONT TestShellSplitErrors89--- PASS: TestShellSplitErrors (0.00s)90=== CONT TestDumpPathSingleFile91=== CONT TestEncodeNixBase32WithRealHash92--- PASS: TestFileTokenReadsAndCaches (0.01s)93=== CONT TestEncodeNixBase3294=== RUN TestEncodeNixBase32/test_string_hash95=== CONT TestDumpPathWriterError96=== CONT TestDoWithRetry_BodyReplayedViaGetBody97=== CONT TestParsePathInfoJSONMultiplePaths98=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess99=== CONT TestRateLimiterFeedback100=== CONT TestPathInfoCACompatibility101=== CONT TestUploadMultipart_SupersededByPeer102=== CONT TestDumpPathMatchesNix103=== CONT TestParsePathInfoJSON104=== CONT TestPathInfoHashCompatibility105=== CONT TestGetStorePathHash106=== CONT TestFileTokenEmpty107=== CONT TestScriptTokenNoExpiryRerunsEveryCall108=== CONT TestPartSizeForNAR109=== CONT TestFileTokenMissing110=== CONT TestScriptTokenEmptyToken111=== CONT TestCaseHackSuffix112=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32113--- PASS: TestEncodeNixBase32WithRealHash (0.00s)114=== PAUSE TestEncodeNixBase32/test_string_hash115=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)116=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths1172026/07/09 07:32:38 WARN Rate limiter enabled after throttle name=server-test rate=5118=== RUN TestRateLimiterFeedback/429_enables_limiter119=== PAUSE TestRateLimiterFeedback/429_enables_limiter120=== RUN TestEncodeNixBase32/empty_input121=== RUN TestPathInfoCACompatibility/null_ca_field122=== RUN TestUploadMultipart_SupersededByPeer/exists123--- PASS: TestResolveStorePath (0.01s)124=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths125=== RUN TestPartSizeForNAR/zero_stays_at_minimum126=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)127--- PASS: TestScriptTokenScriptFails (0.01s)128=== PAUSE TestEncodeNixBase32/empty_input129=== RUN TestGetStorePathHash/valid_store_path130--- PASS: TestScriptTokenBadJSON (0.00s)131=== RUN TestConvertHashToNix32/already_Nix32_format132=== RUN TestRateLimiterFeedback/503_enables_limiter133=== PAUSE TestUploadMultipart_SupersededByPeer/exists134=== RUN TestParsePathInfoJSON/Nix_format135--- PASS: TestFileTokenMissing (0.00s)136=== PAUSE TestRateLimiterFeedback/503_enables_limiter137=== PAUSE TestPathInfoCACompatibility/null_ca_field138=== RUN TestPathInfoCACompatibility/old_string_format_-_text139=== CONT TestEncodeNixBase32/test_string_hash140=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum141=== CONT TestEncodeNixBase32/empty_input142=== PAUSE TestConvertHashToNix32/already_Nix32_format143=== RUN TestConvertHashToNix32/invalid_format144=== PAUSE TestConvertHashToNix32/invalid_format145=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths146=== RUN TestUploadMultipart_SupersededByPeer/missing147=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon148--- PASS: TestFileTokenEmpty (0.01s)149--- PASS: TestScriptTokenEmptyToken (0.01s)150=== PAUSE TestGetStorePathHash/valid_store_path151=== PAUSE TestParsePathInfoJSON/Nix_format152=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter153=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text1542026/07/09 07:32:38 WARN Rate limiter enabled after throttle name=server-test rate=5155=== RUN TestPartSizeForNAR/small_stays_at_minimum156=== CONT TestConvertHashToNix32/SRI_format_to_Nix321572026/07/09 07:32:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:37951158=== CONT TestConvertHashToNix32/invalid_format159=== CONT TestConvertHashToNix32/already_Nix32_format160=== RUN TestSetClientTLSErrors/missing_cert_file161=== PAUSE TestSetClientTLSErrors/missing_cert_file162=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths163=== PAUSE TestUploadMultipart_SupersededByPeer/missing164=== RUN TestSetClientTLS/rejects_connection_without_client_cert165=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths1662026/07/09 07:32:38 WARN Rate limiter backed off name=server-test rate=5167=== RUN TestGetStorePathHash/basename_without_hyphen_should_error1682026/07/09 07:32:38 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:37951169--- PASS: TestScriptTokenCachesUntilRefresh (0.02s)170=== RUN TestParsePathInfoJSON/Lix_format171=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter172=== PAUSE TestParsePathInfoJSON/Lix_format173=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter174=== RUN TestParsePathInfoJSON/empty_input175=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter176=== RUN TestSetClientTLSErrors/missing_key_file177=== PAUSE TestSetClientTLSErrors/missing_key_file178=== CONT TestUploadMultipart_SupersededByPeer/exists179=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths180=== CONT TestUploadMultipart_SupersededByPeer/missing181=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon182=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert183=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI184=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error185--- PASS: TestDoServerRequestAttachesToken (0.03s)186=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error187=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error188=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive189=== PAUSE TestPartSizeForNAR/small_stays_at_minimum190=== PAUSE TestParsePathInfoJSON/empty_input191=== CONT TestRateLimiterFeedback/429_enables_limiter192=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter193=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter194=== CONT TestRateLimiterFeedback/503_enables_limiter195=== RUN TestSetClientTLSErrors/missing_ca_file196=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI197=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA198=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA199--- PASS: TestEncodeNixBase32 (0.01s)200 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)201 --- PASS: TestEncodeNixBase32/empty_input (0.00s)202--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)203=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive204--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.02s)205=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error206=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error207--- PASS: TestConvertHashToNix32 (0.01s)208 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)209 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)210 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)211--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.02s)212=== RUN TestParsePathInfoJSON/whitespace_only213=== RUN TestSetClientTLS/preserves_debug_logging_transport214=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512215=== PAUSE TestSetClientTLSErrors/missing_ca_file216=== RUN TestPathInfoCACompatibility/new_structured_format_-_text217=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum2182026/07/09 07:32:38 WARN Rate limiter enabled after throttle name=server-test rate=5219=== CONT TestGetStorePathHash/valid_store_path2202026/07/09 07:32:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:38097221=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error222=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error2232026/07/09 07:32:38 WARN Rate limiter enabled after throttle name=server-test rate=5224=== CONT TestGetStorePathHash/basename_without_hyphen_should_error2252026/07/09 07:32:38 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:45697226--- PASS: TestParsePathInfoJSONMultiplePaths (0.02s)227 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)228 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)2292026/07/09 07:32:38 WARN Rate limiter backed off name=server-test rate=5230=== PAUSE TestSetClientTLS/preserves_debug_logging_transport231=== CONT TestSetClientTLS/rejects_connection_without_client_cert232=== CONT TestSetClientTLS/preserves_debug_logging_transport233=== PAUSE TestParsePathInfoJSON/whitespace_only234=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512235=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA236=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text237=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum2382026/07/09 07:32:38 WARN Rate limiter backed off name=server-test rate=5239=== RUN TestSetClientTLSErrors/invalid_ca_file240--- PASS: TestDumpPathSingleFile (0.04s)241=== RUN TestParsePathInfoJSON/invalid_JSON242=== PAUSE TestParsePathInfoJSON/invalid_JSON243--- PASS: TestGetStorePathHash (0.03s)244 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)245 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)246 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)247 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)248=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI249--- PASS: TestRateLimiterFeedback (0.02s)250 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)251 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)252 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)253 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.02s)254--- PASS: TestUploadMultipart_SupersededByPeer (0.02s)255 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.02s)256 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.02s)257=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon258=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512259=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method260=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts261=== PAUSE TestSetClientTLSErrors/invalid_ca_file262=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)263--- PASS: TestPathInfoHashCompatibility (0.04s)264 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)265 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)266 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)267 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)268=== CONT TestParsePathInfoJSON/Nix_format269=== CONT TestParsePathInfoJSON/invalid_JSON270=== CONT TestParsePathInfoJSON/whitespace_only271=== CONT TestParsePathInfoJSON/empty_input272=== CONT TestParsePathInfoJSON/Lix_format273--- PASS: TestParsePathInfoJSON (0.04s)274 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)275 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)276 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)277 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)278 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)279=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method280=== CONT TestPathInfoCACompatibility/null_ca_field281=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive282=== CONT TestPathInfoCACompatibility/new_structured_format_-_text283=== CONT TestSetClientTLSErrors/missing_cert_file284=== CONT TestSetClientTLSErrors/missing_key_file285=== CONT TestSetClientTLSErrors/invalid_ca_file286=== CONT TestSetClientTLSErrors/missing_ca_file287=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts288=== RUN TestPartSizeForNAR/1_TiB289=== CONT TestPathInfoCACompatibility/old_string_format_-_text290=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method291=== PAUSE TestPartSizeForNAR/1_TiB292=== RUN TestPartSizeForNAR/5_TiB_S3_max_object293=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object294=== RUN TestPartSizeForNAR/capped_at_5_GiB295--- PASS: TestPathInfoCACompatibility (0.04s)296 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)297 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)298 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)299 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)300 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)301=== PAUSE TestPartSizeForNAR/capped_at_5_GiB302=== CONT TestPartSizeForNAR/zero_stays_at_minimum303=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum304=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts305=== CONT TestPartSizeForNAR/capped_at_5_GiB306=== CONT TestPartSizeForNAR/5_TiB_S3_max_object307=== CONT TestPartSizeForNAR/1_TiB308=== CONT TestPartSizeForNAR/small_stays_at_minimum309--- PASS: TestPartSizeForNAR (0.05s)310 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)311 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)312 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)313 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)314 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)315 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)316 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)317--- PASS: TestSetClientTLSErrors (0.05s)318 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)319 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)320 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)321 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)3222026/07/09 07:32:38 http: TLS handshake error from 127.0.0.1:38818: remote error: tls: bad certificate323--- PASS: TestSetClientTLS (0.04s)324 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)325 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)327--- PASS: TestCaseHackSuffix (0.05s)328--- PASS: TestDumpPathWriterError (0.08s)329--- PASS: TestDumpPathMatchesNix (0.13s)330--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)331PASS332Running server tests...333The files belonging to this database system will be owned by user "nixbld".334This user must also own the server process.335336The database cluster will be initialized with locale "C".337The default database encoding has accordingly been set to "SQL_ASCII".338The default text search configuration will be set to "english".339340Data page checksums are enabled.341342creating directory /build/postgres2978496081/data ... ok343creating subdirectories ... ok344selecting dynamic shared memory implementation ... posix345selecting default "max_connections" ... 100346selecting default "shared_buffers" ... 128MB347selecting default time zone ... UTC348creating configuration files ... ok349running bootstrap script ... ok350performing post-bootstrap initialization ... ok351syncing data to disk ... ok352353initdb: warning: enabling "trust" authentication for local connections354initdb: 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.355356Success. You can now start the database server using:357358 pg_ctl -D /build/postgres2978496081/data -l logfile start359360/build/postgres2978496081:5432 - no response3612026-07-09 07:32:40.263 UTC [208] LOG: starting PostgreSQL 18.4 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit3622026-07-09 07:32:40.263 UTC [208] LOG: listening on Unix socket "/build/postgres2978496081/.s.PGSQL.5432"3632026-07-09 07:32:40.268 UTC [215] LOG: database system was shut down at 2026-07-09 07:32:40 UTC3642026-07-09 07:32:40.272 UTC [208] LOG: database system is ready to accept connections365/build/postgres2978496081:5432 - accepting connections366{"timestamp":"2026-07-09T07:32:40.551308395Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(377)"}367368thread 'rustfs-worker' (418) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:369Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }370note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace371=== RUN TestService_AuthMiddleware372=== PAUSE TestService_AuthMiddleware373=== RUN TestService_AuthMiddleware_MTLSProxyHeader374=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader375=== RUN TestService_AuthMiddleware_MTLSBoundSubjects376=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377=== RUN TestService_ReadAuthMiddleware378=== PAUSE TestService_ReadAuthMiddleware379=== RUN TestService_AuthMiddleware_OIDC380=== PAUSE TestService_AuthMiddleware_OIDC381=== RUN TestCacheConfigHandler382=== PAUSE TestCacheConfigHandler383=== RUN TestCacheStatsHandler384=== PAUSE TestCacheStatsHandler385=== RUN TestClientCADerivations386=== PAUSE TestClientCADerivations387=== RUN TestClientErrorHandling388=== PAUSE TestClientErrorHandling389=== RUN TestClientIntegration390=== PAUSE TestClientIntegration391=== RUN TestClientMultipleUploads392=== PAUSE TestClientMultipleUploads393=== RUN TestClientWithDependencies394=== PAUSE TestClientWithDependencies395=== RUN TestPinProtectsFromGC396=== PAUSE TestPinProtectsFromGC397=== RUN TestGCAdvisoryLockBlocksConcurrentRun3982026-07-09 07:32:40.698 UTC [627] ERROR: relation "goose_db_version" does not exist at character 363992026-07-09 07:32:40.698 UTC [627] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4002026/07/09 07:32:40 OK 20241026095416_initial_model.sql (17.58ms)4012026/07/09 07:32:40 OK 20251210153512_drop_unused_gin_index.sql (2.42ms)4022026/07/09 07:32:40 OK 20251218171726_add_pins.sql (4.92ms)4032026/07/09 07:32:40 OK 20260628120000_add_object_size_and_stats.sql (4.49ms)4042026/07/09 07:32:40 goose: successfully migrated database to version: 202606281200004052026/07/09 07:32:40 OK 1_commit_pending_closure.sql (2.89ms)4062026/07/09 07:32:40 OK 2_object_stats_trigger.sql (1.24ms)4072026/07/09 07:32:40 goose: up to current file version: 2408--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.21s)409=== RUN TestGCBugBareHashReferences410=== PAUSE TestGCBugBareHashReferences411=== RUN TestGCMetrics412=== PAUSE TestGCMetrics413=== RUN TestGCTaskStore_StartNew414=== PAUSE TestGCTaskStore_StartNew415=== RUN TestGCTaskStore_DeduplicateSameParams416=== PAUSE TestGCTaskStore_DeduplicateSameParams417=== RUN TestGCTaskStore_ConflictDifferentParams418=== PAUSE TestGCTaskStore_ConflictDifferentParams419=== RUN TestGCTaskStore_GetEmpty420=== PAUSE TestGCTaskStore_GetEmpty421=== RUN TestGCTaskStore_GetReturnsLatest422=== PAUSE TestGCTaskStore_GetReturnsLatest423=== RUN TestGCTaskStore_CompletedAllowsNewTask424=== PAUSE TestGCTaskStore_CompletedAllowsNewTask425=== RUN TestGCTaskStore_PhaseUpdates426=== PAUSE TestGCTaskStore_PhaseUpdates427=== RUN TestGCTaskStore_Fail428=== PAUSE TestGCTaskStore_Fail429=== RUN TestGracefulShutdownDrainsInflight430=== PAUSE TestGracefulShutdownDrainsInflight431=== RUN TestService_healthCheckHandler432=== PAUSE TestService_healthCheckHandler433=== RUN TestGenerateLandingPage434=== PAUSE TestGenerateLandingPage435=== RUN TestNARDeduplicationMetadataUploadBug436=== PAUSE TestNARDeduplicationMetadataUploadBug437=== RUN TestMetricsInventory438=== PAUSE TestMetricsInventory439=== RUN TestService_NativeMTLS440=== PAUSE TestService_NativeMTLS441=== RUN TestServerTLSConfig442=== PAUSE TestServerTLSConfig443=== RUN TestMultipartCleanup444=== PAUSE TestMultipartCleanup445=== RUN TestObjectStatsTrigger446=== PAUSE TestObjectStatsTrigger447=== RUN TestOrphanedObjectsGC448=== PAUSE TestOrphanedObjectsGC449=== RUN TestOrphanedObjectsGCStressTest450=== PAUSE TestOrphanedObjectsGCStressTest451=== RUN TestResurrectedObjectNotDeleted452=== PAUSE TestResurrectedObjectNotDeleted453=== RUN TestParseSingleRange454=== PAUSE TestParseSingleRange455=== RUN TestIsValidCachePath456=== PAUSE TestIsValidCachePath457=== RUN TestReadProxyNarinfo458=== PAUSE TestReadProxyNarinfo459=== RUN TestReadProxyNarinfoAlreadyDecompressed460=== PAUSE TestReadProxyNarinfoAlreadyDecompressed461=== RUN TestReadProxyNarStreaming462=== PAUSE TestReadProxyNarStreaming463=== RUN TestReadProxy404464=== PAUSE TestReadProxy404465=== RUN TestReadProxyInvalidPath466=== PAUSE TestReadProxyInvalidPath467=== RUN TestReadProxyHead468=== PAUSE TestReadProxyHead469=== RUN TestReadProxyConditionalGet470=== PAUSE TestReadProxyConditionalGet471=== RUN TestReadProxyRootRedirectsToIndexHTML472=== PAUSE TestReadProxyRootRedirectsToIndexHTML473=== RUN TestReadProxyDisabled474=== PAUSE TestReadProxyDisabled475=== RUN TestReadProxyRangeRequest476=== PAUSE TestReadProxyRangeRequest477=== RUN TestRedundantMultipartUpload478=== PAUSE TestRedundantMultipartUpload479=== RUN TestCompleteMultipartUpload_ErrorButObjectExists480=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists481=== RUN TestService_Rustfstest482=== PAUSE TestService_Rustfstest483=== RUN TestSystemdListenerNotActivated484--- PASS: TestSystemdListenerNotActivated (0.00s)485=== RUN TestWatchdogBeatsWhenHealthy486--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)487=== RUN TestWatchdogSkipsWhenUnhealthy4882026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4892026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4902026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4912026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4922026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4932026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4942026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4952026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4962026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4972026/07/09 07:32:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)499=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle500=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle501=== RUN TestProxyWriteTimeout502=== PAUSE TestProxyWriteTimeout503=== RUN TestIsValidUploadKey504=== PAUSE TestIsValidUploadKey505=== RUN TestUploadHandlersRejectInvalidKeys506=== PAUSE TestUploadHandlersRejectInvalidKeys507=== RUN TestUploadHandlersRejectOversizedBody508=== PAUSE TestUploadHandlersRejectOversizedBody509=== RUN TestService_cleanupPendingClosuresHandler510=== PAUSE TestService_cleanupPendingClosuresHandler511=== RUN TestService_createPendingClosureHandler512=== PAUSE TestService_createPendingClosureHandler513=== RUN TestService_verifyS3Integrity514=== PAUSE TestService_verifyS3Integrity515=== RUN TestCompleteMultipartUnregistered516=== PAUSE TestCompleteMultipartUnregistered517=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT518=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT519=== CONT TestIsValidUploadKey520=== CONT TestService_ReadAuthMiddleware521=== CONT TestClientCADerivations522=== CONT TestService_AuthMiddleware523=== CONT TestObjectStatsTrigger524=== CONT TestReadProxyNarStreaming525=== CONT TestProxyWriteTimeout526=== RUN TestProxyWriteTimeout/narinfo527=== PAUSE TestProxyWriteTimeout/narinfo528=== RUN TestProxyWriteTimeout/1_GiB_nar529=== PAUSE TestProxyWriteTimeout/1_GiB_nar530=== RUN TestProxyWriteTimeout/10_GiB_nar531=== CONT TestGenerateLandingPage532=== CONT TestService_healthCheckHandler533=== CONT TestGracefulShutdownDrainsInflight534=== CONT TestGCTaskStore_Fail535--- PASS: TestGCTaskStore_Fail (0.00s)536=== CONT TestGCTaskStore_PhaseUpdates537--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)538=== CONT TestGCTaskStore_CompletedAllowsNewTask539--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)540=== CONT TestNARDeduplicationMetadataUploadBug541=== CONT TestGCTaskStore_GetReturnsLatest542--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)543=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle5442026/07/09 07:32:41 INFO Starting HTTP server address=127.0.0.1:41125545=== CONT TestGCTaskStore_GetEmpty546--- PASS: TestGCTaskStore_GetEmpty (0.00s)547=== CONT TestService_Rustfstest548=== CONT TestGCTaskStore_ConflictDifferentParams549=== CONT TestCompleteMultipartUpload_ErrorButObjectExists550=== CONT TestGCTaskStore_DeduplicateSameParams551=== CONT TestRedundantMultipartUpload552=== CONT TestGCTaskStore_StartNew553=== CONT TestReadProxyRangeRequest554=== CONT TestGCMetrics555=== CONT TestReadProxyDisabled556=== CONT TestGCBugBareHashReferences557=== CONT TestReadProxyRootRedirectsToIndexHTML558=== CONT TestPinProtectsFromGC559=== CONT TestReadProxyConditionalGet560=== CONT TestClientWithDependencies561=== CONT TestReadProxyHead562=== CONT TestCacheConfigHandler563=== CONT TestReadProxyInvalidPath564=== CONT TestService_AuthMiddleware_OIDC565=== CONT TestReadProxy404566=== CONT TestReadProxyNarinfoAlreadyDecompressed567=== CONT TestReadProxyNarinfo568=== CONT TestCacheStatsHandler569=== CONT TestIsValidCachePath570=== CONT TestParseSingleRange571=== CONT TestClientMultipleUploads572=== CONT TestResurrectedObjectNotDeleted573=== CONT TestClientIntegration574=== CONT TestOrphanedObjectsGCStressTest575=== CONT TestClientErrorHandling576=== CONT TestOrphanedObjectsGC577=== CONT TestMultipartCleanup578=== CONT TestServerTLSConfig579=== CONT TestService_NativeMTLS580=== CONT TestMetricsInventory581=== CONT TestService_AuthMiddleware_MTLSProxyHeader582=== CONT TestService_createPendingClosureHandler583=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT584=== CONT TestService_AuthMiddleware_MTLSBoundSubjects585=== CONT TestCompleteMultipartUnregistered586=== CONT TestService_verifyS3Integrity587=== CONT TestUploadHandlersRejectOversizedBody588=== CONT TestService_cleanupPendingClosuresHandler589=== CONT TestUploadHandlersRejectInvalidKeys590=== RUN TestIsValidUploadKey/narinfo591=== PAUSE TestProxyWriteTimeout/10_GiB_nar592--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)593--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)594--- PASS: TestGCTaskStore_StartNew (0.00s)595=== RUN TestCacheConfigHandler/full_config,_no_issuer596=== PAUSE TestCacheConfigHandler/full_config,_no_issuer597=== RUN TestCacheConfigHandler/no_cache_url_configured598=== PAUSE TestCacheConfigHandler/no_cache_url_configured599=== RUN TestCacheConfigHandler/no_signing_keys600=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info601=== PAUSE TestCacheConfigHandler/no_signing_keys6022026/07/09 07:32:41 INFO Shutdown signal received, draining in-flight requests timeout=10s603=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info604=== RUN TestClientErrorHandling/InvalidStorePath605=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator606=== PAUSE TestClientErrorHandling/InvalidStorePath607=== RUN TestClientErrorHandling/InvalidAuthToken608=== RUN TestIsValidCachePath/narinfo609=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal610=== RUN TestParseSingleRange/none611=== RUN TestServerTLSConfig/no_client_CA612=== PAUSE TestIsValidCachePath/narinfo613=== PAUSE TestClientErrorHandling/InvalidAuthToken614=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator615=== CONT TestCacheConfigHandler/full_config,_no_issuer616=== CONT TestCacheConfigHandler/no_signing_keys617=== PAUSE TestIsValidUploadKey/narinfo618=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator619=== CONT TestCacheConfigHandler/no_cache_url_configured620=== RUN TestProxyWriteTimeout/unknown_size621=== PAUSE TestParseSingleRange/none622=== PAUSE TestServerTLSConfig/no_client_CA623=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars624=== RUN TestClientErrorHandling/ServerNotAvailable625=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal626=== RUN TestIsValidUploadKey/nar_zst627=== PAUSE TestProxyWriteTimeout/unknown_size628=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars629=== RUN TestServerTLSConfig/missing_CA_file630=== RUN TestParseSingleRange/unknown_unit631=== PAUSE TestServerTLSConfig/missing_CA_file632=== PAUSE TestParseSingleRange/unknown_unit633=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key634=== PAUSE TestIsValidUploadKey/nar_zst635=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key636=== RUN TestIsValidUploadKey/nar_xz637=== PAUSE TestIsValidUploadKey/nar_xz638=== RUN TestIsValidUploadKey/nar_plain639=== CONT TestProxyWriteTimeout/narinfo640=== CONT TestProxyWriteTimeout/10_GiB_nar641=== CONT TestProxyWriteTimeout/1_GiB_nar642=== PAUSE TestClientErrorHandling/ServerNotAvailable643=== CONT TestClientErrorHandling/InvalidStorePath644=== CONT TestClientErrorHandling/InvalidAuthToken645=== CONT TestClientErrorHandling/ServerNotAvailable646=== RUN TestIsValidCachePath/nar_zst647--- PASS: TestCacheConfigHandler (0.00s)648 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.01s)649 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)650 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)651 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)652=== RUN TestParseSingleRange/multi-range_ignored653=== RUN TestServerTLSConfig/not_a_PEM_file654=== PAUSE TestServerTLSConfig/not_a_PEM_file655=== CONT TestServerTLSConfig/no_client_CA656=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key657=== PAUSE TestIsValidUploadKey/nar_plain658=== CONT TestProxyWriteTimeout/unknown_size659=== RUN TestIsValidUploadKey/listing660=== PAUSE TestIsValidUploadKey/listing661=== PAUSE TestIsValidCachePath/nar_zst662--- PASS: TestProxyWriteTimeout (0.01s)663 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)664 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)665 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)666 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)667=== PAUSE TestParseSingleRange/multi-range_ignored668=== CONT TestServerTLSConfig/not_a_PEM_file669=== CONT TestServerTLSConfig/missing_CA_file670=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key671=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info672=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key673=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key6742026/07/09 07:32:41 INFO Received uploads request method=POST path=/6752026/07/09 07:32:41 INFO Received request for more parts method=POST path=/6762026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/677=== RUN TestIsValidCachePath/nar_xz678=== RUN TestParseSingleRange/malformed_no_dash679=== PAUSE TestIsValidCachePath/nar_xz680=== PAUSE TestParseSingleRange/malformed_no_dash681=== RUN TestIsValidUploadKey/build_log682=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal6832026/07/09 07:32:41 INFO Received uploads request method=POST path=/684=== RUN TestIsValidCachePath/nar_bz2685=== RUN TestParseSingleRange/malformed_both_empty686=== PAUSE TestIsValidUploadKey/build_log687=== PAUSE TestParseSingleRange/malformed_both_empty688=== RUN TestIsValidUploadKey/build_log_home-manager_file689=== PAUSE TestIsValidUploadKey/build_log_home-manager_file690=== RUN TestParseSingleRange/malformed_end_before_start691=== PAUSE TestIsValidCachePath/nar_bz2692--- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)693 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)694 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)695 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)696 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)697=== RUN TestIsValidUploadKey/build_log_plus_in_name698=== PAUSE TestIsValidUploadKey/build_log_plus_in_name699=== PAUSE TestParseSingleRange/malformed_end_before_start700=== RUN TestIsValidCachePath/nar_uncompressed701=== PAUSE TestIsValidCachePath/nar_uncompressed702--- PASS: TestServerTLSConfig (0.01s)703 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)704 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)705 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)706=== RUN TestIsValidUploadKey/build_log_question_mark707=== RUN TestParseSingleRange/closed708=== RUN TestIsValidCachePath/ls709--- PASS: TestGenerateLandingPage (0.01s)710=== PAUSE TestIsValidUploadKey/build_log_question_mark711=== RUN TestIsValidUploadKey/build_log_equals712=== PAUSE TestParseSingleRange/closed713=== PAUSE TestIsValidCachePath/ls714=== PAUSE TestIsValidUploadKey/build_log_equals715=== RUN TestIsValidUploadKey/realisation716=== RUN TestParseSingleRange/open-ended717=== RUN TestIsValidCachePath/log718=== PAUSE TestParseSingleRange/open-ended719=== PAUSE TestIsValidUploadKey/realisation720=== PAUSE TestIsValidCachePath/log7212026/07/09 07:32:41 INFO OIDC provider initialized name=test722=== RUN TestParseSingleRange/end_clamped_to_size723=== PAUSE TestParseSingleRange/end_clamped_to_size724=== RUN TestIsValidUploadKey/realisation_plus_in_output725=== PAUSE TestIsValidUploadKey/realisation_plus_in_output726=== RUN TestIsValidCachePath/realisation727=== RUN TestParseSingleRange/suffix728=== RUN TestIsValidUploadKey/nix-cache-info729=== PAUSE TestIsValidCachePath/realisation730=== PAUSE TestParseSingleRange/suffix731=== PAUSE TestIsValidUploadKey/nix-cache-info732=== RUN TestIsValidCachePath/nix-cache-info733=== RUN TestParseSingleRange/suffix_exceeds_size734=== PAUSE TestParseSingleRange/suffix_exceeds_size735=== RUN TestIsValidUploadKey/index.html736=== PAUSE TestIsValidUploadKey/index.html737=== PAUSE TestIsValidCachePath/nix-cache-info738=== RUN TestParseSingleRange/single_byte739=== RUN TestIsValidUploadKey/narinfo_key,_nar_type740=== RUN TestIsValidCachePath/index.html741=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type742=== PAUSE TestParseSingleRange/single_byte743=== PAUSE TestIsValidCachePath/index.html744=== RUN TestIsValidUploadKey/nar_key,_narinfo_type745=== RUN TestIsValidCachePath/traversal_parent746=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type747=== RUN TestParseSingleRange/start_past_EOF748=== PAUSE TestParseSingleRange/start_past_EOF749=== PAUSE TestIsValidCachePath/traversal_parent750=== RUN TestIsValidCachePath/traversal_in_middle751=== RUN TestIsValidUploadKey/listing_key,_narinfo_type752=== RUN TestParseSingleRange/start_far_past_EOF753=== PAUSE TestIsValidCachePath/traversal_in_middle754=== PAUSE TestParseSingleRange/start_far_past_EOF755=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type756=== CONT TestParseSingleRange/malformed_both_empty757=== RUN TestIsValidUploadKey/traversal758=== CONT TestParseSingleRange/multi-range_ignored759=== CONT TestParseSingleRange/none760=== CONT TestParseSingleRange/start_far_past_EOF761=== CONT TestParseSingleRange/start_past_EOF762=== CONT TestParseSingleRange/single_byte763=== CONT TestParseSingleRange/suffix_exceeds_size764=== CONT TestParseSingleRange/suffix765=== CONT TestParseSingleRange/end_clamped_to_size766=== CONT TestParseSingleRange/open-ended767=== CONT TestParseSingleRange/closed768=== CONT TestParseSingleRange/malformed_end_before_start769=== PAUSE TestIsValidUploadKey/traversal770=== CONT TestParseSingleRange/malformed_no_dash771=== RUN TestIsValidCachePath/invalid_char_e772=== CONT TestParseSingleRange/unknown_unit773=== RUN TestIsValidUploadKey/traversal_nar774=== PAUSE TestIsValidCachePath/invalid_char_e775=== RUN TestIsValidCachePath/invalid_char_u776--- PASS: TestParseSingleRange (0.07s)777 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)778 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)779 --- PASS: TestParseSingleRange/none (0.00s)780 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)781 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)782 --- PASS: TestParseSingleRange/single_byte (0.00s)783 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)784 --- PASS: TestParseSingleRange/suffix (0.00s)785 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)786 --- PASS: TestParseSingleRange/open-ended (0.00s)787 --- PASS: TestParseSingleRange/closed (0.00s)788 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)789 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)790 --- PASS: TestParseSingleRange/unknown_unit (0.00s)791=== PAUSE TestIsValidUploadKey/traversal_nar792=== PAUSE TestIsValidCachePath/invalid_char_u793=== RUN TestIsValidUploadKey/absolute794=== RUN TestIsValidCachePath/random_path795=== PAUSE TestIsValidUploadKey/absolute796=== RUN TestIsValidUploadKey/empty_key797=== PAUSE TestIsValidUploadKey/empty_key798=== RUN TestIsValidUploadKey/unknown_type799=== PAUSE TestIsValidCachePath/random_path800=== PAUSE TestIsValidUploadKey/unknown_type801=== RUN TestIsValidCachePath/empty802=== CONT TestIsValidUploadKey/narinfo803=== CONT TestIsValidUploadKey/unknown_type804=== CONT TestIsValidUploadKey/empty_key805=== CONT TestIsValidUploadKey/build_log_equals806=== CONT TestIsValidUploadKey/build_log_question_mark807=== CONT TestIsValidUploadKey/build_log_plus_in_name808=== CONT TestIsValidUploadKey/realisation809=== CONT TestIsValidUploadKey/build_log_home-manager_file810=== CONT TestIsValidUploadKey/absolute811=== CONT TestIsValidUploadKey/build_log812=== CONT TestIsValidUploadKey/traversal_nar813=== CONT TestIsValidUploadKey/listing814=== CONT TestIsValidUploadKey/traversal815=== CONT TestIsValidUploadKey/nar_plain816=== CONT TestIsValidUploadKey/listing_key,_narinfo_type817=== CONT TestIsValidUploadKey/nar_xz818=== CONT TestIsValidUploadKey/nar_key,_narinfo_type819=== CONT TestIsValidUploadKey/nar_zst820=== CONT TestIsValidUploadKey/nix-cache-info821=== CONT TestIsValidUploadKey/realisation_plus_in_output822=== CONT TestIsValidUploadKey/narinfo_key,_nar_type823=== CONT TestIsValidUploadKey/index.html824=== PAUSE TestIsValidCachePath/empty825--- PASS: TestIsValidUploadKey (0.07s)826 --- PASS: TestIsValidUploadKey/narinfo (0.00s)827 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)828 --- PASS: TestIsValidUploadKey/empty_key (0.00s)829 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)830 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)831 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)832 --- PASS: TestIsValidUploadKey/realisation (0.00s)833 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)834 --- PASS: TestIsValidUploadKey/absolute (0.00s)835 --- PASS: TestIsValidUploadKey/build_log (0.00s)836 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)837 --- PASS: TestIsValidUploadKey/listing (0.00s)838 --- PASS: TestIsValidUploadKey/traversal (0.00s)839 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)840 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)841 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)842 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)843 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)844 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)845 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)846 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)847 --- PASS: TestIsValidUploadKey/index.html (0.00s)848=== RUN TestIsValidCachePath/leading_slash849=== PAUSE TestIsValidCachePath/leading_slash850=== RUN TestIsValidCachePath/wrong_extension851=== PAUSE TestIsValidCachePath/wrong_extension852=== RUN TestIsValidCachePath/short_hash853=== PAUSE TestIsValidCachePath/short_hash854--- PASS: TestGracefulShutdownDrainsInflight (0.07s)855=== CONT TestIsValidCachePath/empty856=== CONT TestIsValidCachePath/narinfo857=== CONT TestIsValidCachePath/short_hash858=== CONT TestIsValidCachePath/random_path859=== CONT TestIsValidCachePath/wrong_extension860=== CONT TestIsValidCachePath/invalid_char_u861=== CONT TestIsValidCachePath/ls862=== CONT TestIsValidCachePath/leading_slash863=== CONT TestIsValidCachePath/nar_uncompressed864=== CONT TestIsValidCachePath/log865=== CONT TestIsValidCachePath/nar_xz866=== CONT TestIsValidCachePath/nar_zst867=== CONT TestIsValidCachePath/invalid_char_e868=== CONT TestIsValidCachePath/nar_bz2869=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars870=== CONT TestIsValidCachePath/traversal_in_middle871=== CONT TestIsValidCachePath/traversal_parent872=== CONT TestIsValidCachePath/index.html873=== CONT TestIsValidCachePath/nix-cache-info874=== CONT TestIsValidCachePath/realisation875--- PASS: TestIsValidCachePath (0.07s)876 --- PASS: TestIsValidCachePath/empty (0.00s)877 --- PASS: TestIsValidCachePath/narinfo (0.00s)878 --- PASS: TestIsValidCachePath/short_hash (0.00s)879 --- PASS: TestIsValidCachePath/random_path (0.00s)880 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)881 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)882 --- PASS: TestIsValidCachePath/ls (0.00s)883 --- PASS: TestIsValidCachePath/leading_slash (0.00s)884 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)885 --- PASS: TestIsValidCachePath/log (0.00s)886 --- PASS: TestIsValidCachePath/nar_xz (0.00s)887 --- PASS: TestIsValidCachePath/nar_zst (0.00s)888 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)889 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)890 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)891 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)892 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)893 --- PASS: TestIsValidCachePath/index.html (0.00s)894 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)895 --- PASS: TestIsValidCachePath/realisation (0.00s)8962026-07-09 07:32:41.132 UTC [769] ERROR: relation "goose_db_version" does not exist at character 368972026-07-09 07:32:41.132 UTC [769] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC898=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart899=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart900=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts901=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts902=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure903=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure904=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart905=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure9062026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/907=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts9082026/07/09 07:32:41 INFO Received uploads request method=POST path=/9092026/07/09 07:32:41 INFO Received request for more parts method=POST path=/9102026-07-09 07:32:41.182 UTC [789] ERROR: relation "goose_db_version" does not exist at character 369112026-07-09 07:32:41.182 UTC [789] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9122026-07-09 07:32:41.182 UTC [790] ERROR: relation "goose_db_version" does not exist at character 369132026-07-09 07:32:41.182 UTC [790] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9142026-07-09 07:32:41.183 UTC [792] ERROR: relation "goose_db_version" does not exist at character 369152026-07-09 07:32:41.183 UTC [792] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9162026-07-09 07:32:41.183 UTC [788] ERROR: relation "goose_db_version" does not exist at character 369172026-07-09 07:32:41.183 UTC [788] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9182026-07-09 07:32:41.184 UTC [791] ERROR: relation "goose_db_version" does not exist at character 369192026-07-09 07:32:41.184 UTC [791] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9202026/07/09 07:32:41 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_closures9212026/07/09 07:32:41 OK 20241026095416_initial_model.sql (37.23ms)9222026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.3ms)9232026-07-09 07:32:41.223 UTC [834] ERROR: relation "goose_db_version" does not exist at character 369242026-07-09 07:32:41.223 UTC [834] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9252026/07/09 07:32:41 OK 20251218171726_add_pins.sql (29.01ms)9262026-07-09 07:32:41.238 UTC [835] ERROR: relation "goose_db_version" does not exist at character 369272026-07-09 07:32:41.238 UTC [835] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9282026-07-09 07:32:41.238 UTC [836] ERROR: relation "goose_db_version" does not exist at character 369292026-07-09 07:32:41.238 UTC [836] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9302026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (42.91ms)9312026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009322026/07/09 07:32:41 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=184.724724ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures9332026/07/09 07:32:41 OK 1_commit_pending_closure.sql (20.14ms)9342026/07/09 07:32:41 OK 2_object_stats_trigger.sql (6.61ms)9352026/07/09 07:32:41 goose: up to current file version: 29362026/07/09 07:32:41 OK 20241026095416_initial_model.sql (103.89ms)9372026/07/09 07:32:41 OK 20241026095416_initial_model.sql (108.8ms)9382026/07/09 07:32:41 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"939--- PASS: TestService_AuthMiddleware (0.31s)9402026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.42ms)9412026/07/09 07:32:41 OK 20241026095416_initial_model.sql (109.95ms)9422026/07/09 07:32:41 OK 20241026095416_initial_model.sql (111.32ms)9432026/07/09 07:32:41 OK 20241026095416_initial_model.sql (112.45ms)9442026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.02ms)9452026-07-09 07:32:41.328 UTC [838] ERROR: relation "goose_db_version" does not exist at character 369462026-07-09 07:32:41.328 UTC [838] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9472026-07-09 07:32:41.329 UTC [840] ERROR: relation "goose_db_version" does not exist at character 369482026-07-09 07:32:41.329 UTC [840] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9492026-07-09 07:32:41.329 UTC [839] ERROR: relation "goose_db_version" does not exist at character 369502026-07-09 07:32:41.329 UTC [839] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9512026-07-09 07:32:41.329 UTC [841] ERROR: relation "goose_db_version" does not exist at character 369522026-07-09 07:32:41.329 UTC [841] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9532026-07-09 07:32:41.331 UTC [842] ERROR: relation "goose_db_version" does not exist at character 369542026-07-09 07:32:41.331 UTC [842] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9552026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (21.5ms)9562026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (20.29ms)9572026/07/09 07:32:41 OK 20251218171726_add_pins.sql (16.34ms)9582026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (23.9ms)9592026/07/09 07:32:41 OK 20251218171726_add_pins.sql (30.65ms)9602026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (10.52ms)9612026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009622026/07/09 07:32:41 OK 20251218171726_add_pins.sql (13.56ms)9632026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.66ms)9642026/07/09 07:32:41 OK 20251218171726_add_pins.sql (20.08ms)9652026/07/09 07:32:41 OK 20241026095416_initial_model.sql (59.71ms)9662026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (16.02ms)9672026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009682026/07/09 07:32:41 OK 2_object_stats_trigger.sql (21.23ms)9692026/07/09 07:32:41 goose: up to current file version: 29702026/07/09 07:32:41 OK 20241026095416_initial_model.sql (67.84ms)9712026/07/09 07:32:41 OK 20251218171726_add_pins.sql (33.88ms)9722026/07/09 07:32:41 OK 1_commit_pending_closure.sql (16.63ms)9732026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (28.33ms)9742026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009752026/07/09 07:32:41 OK 20241026095416_initial_model.sql (72.76ms)9762026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (21.8ms)9772026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009782026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (21.53ms)9792026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.7ms)9802026/07/09 07:32:41 goose: up to current file version: 29812026/07/09 07:32:41 OK 20241026095416_initial_model.sql (42.63ms)9822026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.72ms)9832026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (8ms)9842026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.82ms)9852026/07/09 07:32:41 OK 1_commit_pending_closure.sql (7.09ms)9862026/07/09 07:32:41 OK 20241026095416_initial_model.sql (46.84ms)9872026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.92ms)9882026/07/09 07:32:41 goose: up to current file version: 29892026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.01ms)9902026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (13.34ms)9912026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200009922026/07/09 07:32:41 goose: up to current file version: 29932026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.5ms)9942026/07/09 07:32:41 OK 20251218171726_add_pins.sql (13.07ms)9952026/07/09 07:32:41 OK 20241026095416_initial_model.sql (34.09ms)996{"timestamp":"2026-07-09T07:32:41.393713065Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(379)"}9972026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.55ms)998--- PASS: TestService_healthCheckHandler (0.39s)9992026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket61000--- PASS: TestReadProxyNarStreaming (0.40s)10012026-07-09 07:32:41.407 UTC [843] ERROR: relation "goose_db_version" does not exist at character 3610022026-07-09 07:32:41.407 UTC [843] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10032026-07-09 07:32:41.408 UTC [844] ERROR: relation "goose_db_version" does not exist at character 3610042026-07-09 07:32:41.408 UTC [844] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10052026-07-09 07:32:41.409 UTC [845] ERROR: relation "goose_db_version" does not exist at character 3610062026-07-09 07:32:41.409 UTC [845] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10072026-07-09 07:32:41.410 UTC [846] ERROR: relation "goose_db_version" does not exist at character 3610082026-07-09 07:32:41.410 UTC [846] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10092026-07-09 07:32:41.411 UTC [847] ERROR: relation "goose_db_version" does not exist at character 3610102026-07-09 07:32:41.411 UTC [847] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10112026-07-09 07:32:41.411 UTC [848] ERROR: relation "goose_db_version" does not exist at character 3610122026-07-09 07:32:41.411 UTC [848] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10132026/07/09 07:32:41 OK 20251218171726_add_pins.sql (26.11ms)10142026/07/09 07:32:41 OK 20241026095416_initial_model.sql (52.77ms)10152026/07/09 07:32:41 OK 20241026095416_initial_model.sql (52.75ms)10162026/07/09 07:32:41 OK 1_commit_pending_closure.sql (22.06ms)10172026/07/09 07:32:41 OK 20251218171726_add_pins.sql (29.76ms)10182026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (22.46ms)10192026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.55ms)10202026/07/09 07:32:41 goose: up to current file version: 210212026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (7.48ms)10222026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (7.37ms)10232026/07/09 07:32:41 OK 20251218171726_add_pins.sql (33.56ms)10242026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket710252026/07/09 07:32:41 OK 20251218171726_add_pins.sql (33.12ms)10262026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (33.68ms)10272026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010282026/07/09 07:32:41 OK 20251218171726_add_pins.sql (10.39ms)10292026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (15.16ms)10302026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010312026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (18.65ms)10322026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010332026/07/09 07:32:41 OK 20251218171726_add_pins.sql (14.78ms)10342026/07/09 07:32:41 OK 1_commit_pending_closure.sql (4.5ms)10352026/07/09 07:32:41 OK 20251218171726_add_pins.sql (13ms)1036=== NAME TestNARDeduplicationMetadataUploadBug1037 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug122429254/001/store/pvgw8gy14xn2yiwqviwdmg2n3gx2kqpn-file1.txt10382026/07/09 07:32:41 OK 2_object_stats_trigger.sql (15.94ms)10392026/07/09 07:32:41 goose: up to current file version: 210402026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (18.89ms)10412026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010422026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (24.78ms)10432026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200001044--- PASS: TestObjectStatsTrigger (0.45s)10452026/07/09 07:32:41 OK 1_commit_pending_closure.sql (19.01ms)10462026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (22.9ms)10472026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010482026/07/09 07:32:41 OK 1_commit_pending_closure.sql (19ms)10492026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (22.24ms)10502026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010512026/07/09 07:32:41 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1052--- PASS: TestService_ReadAuthMiddleware (0.45s)10532026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.11ms)10542026/07/09 07:32:41 goose: up to current file version: 210552026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.39ms)10562026/07/09 07:32:41 goose: up to current file version: 210572026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.34ms)10582026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.71ms)10592026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.83ms)10602026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (23.51ms)10612026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000010622026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.8ms)10632026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures10642026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.69ms)10652026/07/09 07:32:41 goose: up to current file version: 210662026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.81ms)10672026/07/09 07:32:41 goose: up to current file version: 210682026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.92ms)10692026/07/09 07:32:41 goose: up to current file version: 21070--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.46s)10712026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.7ms)10722026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.36ms)10732026/07/09 07:32:41 goose: up to current file version: 210742026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures10752026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures10762026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures1077--- PASS: TestService_Rustfstest (0.46s)10782026/07/09 07:32:41 OK 20241026095416_initial_model.sql (38.59ms)1079--- PASS: TestReadProxy404 (0.46s)10802026/07/09 07:32:41 OK 2_object_stats_trigger.sql (6.01ms)10812026/07/09 07:32:41 goose: up to current file version: 210822026/07/09 07:32:41 OK 20241026095416_initial_model.sql (37.8ms)10832026/07/09 07:32:41 OK 20241026095416_initial_model.sql (37.82ms)10842026/07/09 07:32:41 OK 20241026095416_initial_model.sql (37.9ms)10852026-07-09 07:32:41.474 UTC [887] ERROR: relation "goose_db_version" does not exist at character 3610862026-07-09 07:32:41.474 UTC [887] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10872026-07-09 07:32:41.474 UTC [884] ERROR: relation "goose_db_version" does not exist at character 3610882026-07-09 07:32:41.474 UTC [884] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10892026-07-09 07:32:41.476 UTC [888] ERROR: relation "goose_db_version" does not exist at character 3610902026-07-09 07:32:41.476 UTC [888] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10912026/07/09 07:32:41 INFO Aborted multipart uploads count=010922026/07/09 07:32:41 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=406.126294ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures10932026-07-09 07:32:41.478 UTC [890] ERROR: relation "goose_db_version" does not exist at character 3610942026-07-09 07:32:41.478 UTC [890] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10952026-07-09 07:32:41.480 UTC [891] ERROR: relation "goose_db_version" does not exist at character 3610962026-07-09 07:32:41.480 UTC [891] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10972026-07-09 07:32:41.480 UTC [893] ERROR: relation "goose_db_version" does not exist at character 3610982026-07-09 07:32:41.480 UTC [893] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10992026/07/09 07:32:41 WARN Force mode enabled - objects will be deleted immediately without grace period11002026-07-09 07:32:41.481 UTC [889] ERROR: relation "goose_db_version" does not exist at character 3611012026-07-09 07:32:41.481 UTC [889] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11022026/07/09 07:32:41 OK 20241026095416_initial_model.sql (51.04ms)11032026/07/09 07:32:41 OK 20241026095416_initial_model.sql (52.05ms)11042026/07/09 07:32:41 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=011052026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (16.37ms)11062026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (16.3ms)11072026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (18.71ms)11082026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (16.17ms)11092026/07/09 07:32:41 INFO Vacuumed table table=pending_closures11102026/07/09 07:32:41 INFO Vacuumed table table=pending_objects11112026/07/09 07:32:41 INFO Vacuumed table table=multipart_uploads11122026/07/09 07:32:41 INFO Vacuumed table table=closures11132026/07/09 07:32:41 INFO Vacuumed table table=objects11142026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.27ms)11152026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.23ms)11162026/07/09 07:32:41 OK 20251218171726_add_pins.sql (8.04ms)11172026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.72ms)11182026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.98ms)11192026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.8ms)1120--- PASS: TestGCMetrics (0.49s)11212026/07/09 07:32:41 OK 20251218171726_add_pins.sql (11.88ms)11222026/07/09 07:32:41 OK 20251218171726_add_pins.sql (12.29ms)11232026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (8.21ms)11242026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011252026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (8.14ms)11262026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011272026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (8.46ms)11282026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (10.27ms)11292026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011302026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011312026-07-09 07:32:41.505 UTC [913] ERROR: relation "goose_db_version" does not exist at character 3611322026-07-09 07:32:41.505 UTC [913] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11332026-07-09 07:32:41.505 UTC [914] ERROR: relation "goose_db_version" does not exist at character 3611342026-07-09 07:32:41.505 UTC [914] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11352026-07-09 07:32:41.506 UTC [915] ERROR: relation "goose_db_version" does not exist at character 3611362026-07-09 07:32:41.506 UTC [915] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11372026/07/09 07:32:41 OK 1_commit_pending_closure.sql (4.42ms)11382026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.5ms)11392026-07-09 07:32:41.511 UTC [932] ERROR: relation "goose_db_version" does not exist at character 3611402026-07-09 07:32:41.511 UTC [932] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11412026-07-09 07:32:41.511 UTC [933] ERROR: relation "goose_db_version" does not exist at character 3611422026-07-09 07:32:41.511 UTC [933] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11432026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.97ms)11442026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.76ms)11452026-07-09 07:32:41.512 UTC [934] ERROR: relation "goose_db_version" does not exist at character 3611462026-07-09 07:32:41.512 UTC [934] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11472026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (9.07ms)11482026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011492026-07-09 07:32:41.514 UTC [935] ERROR: relation "goose_db_version" does not exist at character 3611502026-07-09 07:32:41.514 UTC [935] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11512026-07-09 07:32:41.514 UTC [936] ERROR: relation "goose_db_version" does not exist at character 3611522026-07-09 07:32:41.514 UTC [936] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11532026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.76ms)11542026/07/09 07:32:41 goose: up to current file version: 211552026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (10.65ms)11562026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000011572026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.84ms)11582026-07-09 07:32:41.515 UTC [937] ERROR: relation "goose_db_version" does not exist at character 3611592026-07-09 07:32:41.515 UTC [937] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11602026/07/09 07:32:41 goose: up to current file version: 211612026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.88ms)11622026/07/09 07:32:41 goose: up to current file version: 211632026/07/09 07:32:41 OK 2_object_stats_trigger.sql (5.06ms)11642026/07/09 07:32:41 goose: up to current file version: 211652026-07-09 07:32:41.516 UTC [938] ERROR: relation "goose_db_version" does not exist at character 3611662026-07-09 07:32:41.516 UTC [938] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11672026-07-09 07:32:41.517 UTC [939] ERROR: relation "goose_db_version" does not exist at character 3611682026-07-09 07:32:41.517 UTC [939] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11692026/07/09 07:32:41 OK 20241026095416_initial_model.sql (15.59ms)11702026-07-09 07:32:41.518 UTC [940] ERROR: relation "goose_db_version" does not exist at character 3611712026-07-09 07:32:41.518 UTC [940] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11722026-07-09 07:32:41.519 UTC [943] ERROR: relation "goose_db_version" does not exist at character 3611732026-07-09 07:32:41.519 UTC [943] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11742026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.18ms)11752026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11762026-07-09 07:32:41.520 UTC [944] ERROR: relation "goose_db_version" does not exist at character 3611772026-07-09 07:32:41.520 UTC [944] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11782026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures11792026-07-09 07:32:41.520 UTC [961] ERROR: relation "goose_db_version" does not exist at character 3611802026-07-09 07:32:41.520 UTC [961] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11812026-07-09 07:32:41.520 UTC [945] ERROR: relation "goose_db_version" does not exist at character 3611822026-07-09 07:32:41.520 UTC [945] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1183=== NAME TestClientCADerivations1184 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2740151274/001/store/xzbml5pci4ym891i1q15wldzvcqxipdy-ca-test11852026/07/09 07:32:41 OK 20241026095416_initial_model.sql (20.03ms)1186--- PASS: TestReadProxyDisabled (0.52s)1187{"timestamp":"2026-07-09T07:32:41.523007549Z","level":"ERROR","fields":{"message":"complete_multipart_upload part error: \"part.1 not found\""},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3463,"threadName":"rustfs-worker","threadId":"ThreadId(379)"}1188{"timestamp":"2026-07-09T07:32:41.52312625Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket9, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(379)"}11892026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.74ms)11902026/07/09 07:32:41 goose: up to current file version: 211912026/07/09 07:32:41 OK 1_commit_pending_closure.sql (7.89ms)11922026/07/09 07:32:41 OK 20241026095416_initial_model.sql (18.76ms)11932026/07/09 07:32:41 OK 20241026095416_initial_model.sql (21.48ms)11942026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.52ms)11952026/07/09 07:32:41 OK 20241026095416_initial_model.sql (19.57ms)11962026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket1711972026/07/09 07:32:41 WARN CompleteMultipartUpload errored but object exists; treating as success error="One or more of the specified parts could not be found. The part may not have been uploaded, or the specified entity tag may not match the part's entity tag." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZjY2ZjMyOWYtYWEyMy00OTFkLWFkODYtOTM3MGE4NGM2NzBkLjFhNTllOWYxLTZlZjQtNDJlNC05YzNiLTNlZGE3OTcxOWMxOHgxNzgzNTgyMzYxNDg4MjEwNTAz11982026/07/09 07:32:41 OK 20241026095416_initial_model.sql (19.26ms)11992026/07/09 07:32:41 OK 20241026095416_initial_model.sql (21.87ms)12002026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.59ms)12012026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.98ms)12022026/07/09 07:32:41 goose: up to current file version: 212032026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.11ms)12042026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.04ms)12052026/07/09 07:32:41 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZjY2ZjMyOWYtYWEyMy00OTFkLWFkODYtOTM3MGE4NGM2NzBkLjFhNTllOWYxLTZlZjQtNDJlNC05YzNiLTNlZGE3OTcxOWMxOHgxNzgzNTgyMzYxNDg4MjEwNTAz parts=11206--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.53s)12072026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.17ms)12082026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.18ms)12092026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.13ms)12102026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.5ms)12112026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket2012122026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.67ms)12132026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.46ms)12142026/07/09 07:32:41 OK 20241026095416_initial_model.sql (16.26ms)12152026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.15ms)1216--- PASS: TestReadProxyNarinfo (0.53s)12172026/07/09 07:32:41 OK 20251218171726_add_pins.sql (7.04ms)12182026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.5ms)12192026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.81ms)12202026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012212026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.9ms)12222026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012232026/07/09 07:32:41 OK 20251218171726_add_pins.sql (10.14ms)12242026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.63ms)12252026/07/09 07:32:41 OK 20251218171726_add_pins.sql (8.33ms)12262026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (3.23ms)12272026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (7ms)12282026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012292026/07/09 07:32:41 OK 20241026095416_initial_model.sql (19.37ms)12302026/07/09 07:32:41 OK 20241026095416_initial_model.sql (15.53ms)12312026/07/09 07:32:41 OK 20241026095416_initial_model.sql (14.68ms)12322026/07/09 07:32:41 OK 20241026095416_initial_model.sql (14.33ms)12332026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.32ms)12342026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.22ms)12352026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012362026/07/09 07:32:41 OK 20241026095416_initial_model.sql (16.9ms)12372026/07/09 07:32:41 OK 20241026095416_initial_model.sql (13.53ms)12382026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.1ms)12392026/07/09 07:32:41 goose: successfully migrated database to version: 202606281200001240--- PASS: TestReadProxyHead (0.54s)12412026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.43ms)12422026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.99ms)12432026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.89ms)12442026/07/09 07:32:41 OK 2_object_stats_trigger.sql (5.22ms)12452026/07/09 07:32:41 goose: up to current file version: 212462026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.95ms)12472026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.95ms)12482026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.19ms)12492026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.43ms)12502026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (5.28ms)12512026/07/09 07:32:41 OK 20241026095416_initial_model.sql (18.73ms)12522026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.96ms)12532026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.87ms)12542026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012552026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.21ms)12562026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.85ms)12572026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.97ms)12582026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5ms)12592026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.16ms)12602026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.36ms)12612026/07/09 07:32:41 OK 20241026095416_initial_model.sql (14.01ms)12622026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (8.43ms)12632026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012642026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.74ms)12652026/07/09 07:32:41 OK 2_object_stats_trigger.sql (2.43ms)12662026/07/09 07:32:41 goose: up to current file version: 212672026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.13ms)12682026/07/09 07:32:41 goose: up to current file version: 212692026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (3.96ms)12702026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (3.55ms)12712026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.5ms)12722026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.79ms)12732026/07/09 07:32:41 goose: up to current file version: 212742026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.93ms)12752026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.35ms)12762026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.06ms)12772026/07/09 07:32:41 OK 2_object_stats_trigger.sql (4.16ms)12782026/07/09 07:32:41 goose: up to current file version: 212792026/07/09 07:32:41 OK 20241026095416_initial_model.sql (17.16ms)12802026/07/09 07:32:41 OK 1_commit_pending_closure.sql (6.2ms)12812026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.77ms)12822026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.96ms)12832026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012842026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.82ms)12852026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.52ms)12862026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.65ms)12872026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.07ms)12882026/07/09 07:32:41 OK 1_commit_pending_closure.sql (5.43ms)12892026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (6.35ms)12902026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (7ms)12912026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000012922026/07/09 07:32:41 OK 20241026095416_initial_model.sql (19.55ms)1293=== NAME TestClientCADerivations1294 client_ca_test.go:139: Found 1 dependencies (including self)12952026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.16ms)12962026/07/09 07:32:41 OK 2_object_stats_trigger.sql (2.85ms)12972026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.32ms)12982026/07/09 07:32:41 goose: up to current file version: 212992026/07/09 07:32:41 OK 20251218171726_add_pins.sql (4.53ms)13002026/07/09 07:32:41 goose: up to current file version: 213012026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (4.54ms)13022026/07/09 07:32:41 OK 20251218171726_add_pins.sql (6.17ms)13032026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures13042026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket2313052026/07/09 07:32:41 OK 1_commit_pending_closure.sql (4.12ms)13062026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.36ms)13072026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (5.72ms)13082026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013092026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.53ms)13102026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.45ms)13112026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.37ms)13122026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013132026/07/09 07:32:41 OK 20251210153512_drop_unused_gin_index.sql (3.66ms)13142026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.59ms)13152026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013162026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.78ms)13172026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013182026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.93ms)13192026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013202026/07/09 07:32:41 OK 20251218171726_add_pins.sql (4.62ms)13212026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (5.48ms)13222026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013232026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures13242026/07/09 07:32:41 INFO Received cleanup request method=DELETE path=/api/pending_closures13252026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3ms)13262026/07/09 07:32:41 goose: up to current file version: 213272026/07/09 07:32:41 OK 2_object_stats_trigger.sql (3.02ms)13282026/07/09 07:32:41 goose: up to current file version: 213292026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (5.18ms)13302026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013312026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.7ms)13322026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.55ms)13332026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (6.44ms)13342026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013352026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.63ms)13362026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (5.38ms)13372026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013382026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.4ms)13392026/07/09 07:32:41 OK 1_commit_pending_closure.sql (4.2ms)13402026/07/09 07:32:41 OK 1_commit_pending_closure.sql (4.02ms)13412026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.09ms)13422026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.56ms)13432026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013442026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.49ms)13452026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013462026/07/09 07:32:41 OK 20251218171726_add_pins.sql (5.19ms)13472026/07/09 07:32:41 INFO Aborted multipart uploads count=013482026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.86ms)13492026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013502026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.62ms)13512026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.77ms)1352--- PASS: TestCacheStatsHandler (0.56s)13532026/07/09 07:32:41 goose: up to current file version: 213542026/07/09 07:32:41 goose: up to current file version: 213552026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.76ms)13562026/07/09 07:32:41 goose: up to current file version: 213572026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.37ms)13582026/07/09 07:32:41 goose: up to current file version: 213592026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.6ms)13602026/07/09 07:32:41 goose: up to current file version: 213612026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.84ms)13622026/07/09 07:32:41 goose: up to current file version: 213632026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.12ms)13642026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.55ms)13652026/07/09 07:32:41 WARN mTLS auth: subject not in bound subjects subject="CN=reader"13662026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures1367=== NAME TestClientIntegration13682026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.16ms)1369 client_integration_test.go:276: Created store path: /build/TestClientIntegration3898247121/002/store/8jrc144nzqjdgsaznw49h34jd3hfwnla-test-file.txt13702026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.22ms)13712026/07/09 07:32:41 WARN mTLS auth: subject not in bound subjects subject="CN=writer"13722026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.75ms)1373--- PASS: TestService_NativeMTLS (0.56s)13742026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.18ms)13752026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.54ms)13762026/07/09 07:32:41 goose: up to current file version: 213772026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.19ms)13782026/07/09 07:32:41 goose: up to current file version: 213792026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.74ms)13802026/07/09 07:32:41 goose: up to current file version: 213812026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.41ms)13822026/07/09 07:32:41 goose: up to current file version: 213832026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.44ms)13842026/07/09 07:32:41 goose: up to current file version: 213852026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.43ms)13862026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013872026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.79ms)13882026/07/09 07:32:41 goose: up to current file version: 213892026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures13902026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)13912026/07/09 07:32:41 OK 20260628120000_add_object_size_and_stats.sql (4.86ms)13922026/07/09 07:32:41 goose: successfully migrated database to version: 2026062812000013932026/07/09 07:32:41 INFO Uploading pvgw8gy14xn2yiwqviwdmg2n3gx2kqpn-file1.txt (160B)1394--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.56s)13952026/07/09 07:32:41 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"13962026/07/09 07:32:41 WARN mTLS auth: bound subjects configured but subject DN unavailable13972026/07/09 07:32:41 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1398--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.57s)13992026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures14002026/07/09 07:32:41 OK 1_commit_pending_closure.sql (2.51ms)1401--- PASS: TestReadProxyInvalidPath (0.57s)14022026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.2ms)14032026/07/09 07:32:41 goose: up to current file version: 214042026/07/09 07:32:41 OK 1_commit_pending_closure.sql (3.43ms)14052026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures1406--- PASS: TestReadProxyRangeRequest (0.57s)1407=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token1408=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1409=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1410=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1411=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1412=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1413=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1414=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1415=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1416=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1417=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1418=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected14192026/07/09 07:32:41 WARN Authentication failed token_preview=not-a-valid-jwt token_length=15 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]14202026/07/09 07:32:41 OK 2_object_stats_trigger.sql (1.45ms)14212026/07/09 07:32:41 goose: up to current file version: 214222026/07/09 07:32:41 INFO Received cleanup request method=DELETE path=/api/pending_closures14232026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14242026/07/09 07:32:41 INFO Created nix-cache-info in bucket bucket=bucket3814252026/07/09 07:32:41 INFO Aborted multipart uploads count=114262026/07/09 07:32:41 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1427--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.57s)1428--- PASS: TestCompleteMultipartUnregistered (0.57s)1429--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.58s)14302026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14312026/07/09 07:32:41 INFO OIDC auth successful provider=test14322026/07/09 07:32:41 WARN Authentication failed token_preview=eyJhbGciOi...DfJYaC4H2g token_length=702 oidc_error="bound claims validation failed: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]14332026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1434--- PASS: TestMetricsInventory (0.58s)14352026-07-09 07:32:41.585 UTC [889] ERROR: Closure does not exist: id=114362026-07-09 07:32:41.585 UTC [889] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE14372026-07-09 07:32:41.585 UTC [889] STATEMENT: -- name: CommitPendingClosure :exec1438 SELECT commit_pending_closure($1::bigint)1439 14402026/07/09 07:32:41 INFO Signed narinfos id=1 count=11441--- PASS: TestService_AuthMiddleware_OIDC (0.57s)1442 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1443 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1444 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.01s)1445 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.01s)1446--- PASS: TestService_cleanupPendingClosuresHandler (0.58s)14472026/07/09 07:32:41 INFO Uploading 1 narinfos14482026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures14492026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1450--- PASS: TestReadProxyConditionalGet (0.59s)14512026/07/09 07:32:41 INFO Completed upload id=114522026/07/09 07:32:41 INFO Upload complete. (120ms)1453=== NAME TestClientMultipleUploads1454 client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads3827395244/001/store/k8gimdjksjvkihzghvjxw38pb06sgdw7-test-file-0.txt1455=== NAME TestNARDeduplicationMetadataUploadBug1456 metadata_upload_test.go:54: Retrieved narinfo from S3:1457 StorePath: /build/TestNARDeduplicationMetadataUploadBug122429254/001/store/pvgw8gy14xn2yiwqviwdmg2n3gx2kqpn-file1.txt1458 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1459 Compression: zstd1460 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1461 NarSize: 1601462 References: 1463 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf14642026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1465 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1466 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1467 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1468=== NAME TestPinProtectsFromGC1469 client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC1570481432/001/store/y3k30jq636vwfrad31y2308xr7qvy3g9-pinned-file.txt1470 client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC1570481432/001/store/m2j6281ymbgpvimnapa7ijqi1jfidl58-unpinned-file.txt1471--- PASS: TestResurrectedObjectNotDeleted (0.61s)1472=== NAME TestClientMultipleUploads1473 client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads3827395244/001/store/7qrca2ryq253i90blpnkmcj06l3cyyks-test-file-1.txt1474=== NAME TestNARDeduplicationMetadataUploadBug1475 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug122429254/001/store/s3jibmrhxzggwc323s8cdjwklx9ybhac-file2.txt14762026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures14772026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14782026/07/09 07:32:41 INFO Uploading xzbml5pci4ym891i1q15wldzvcqxipdy-ca-test (144B)14792026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures14802026/07/09 07:32:41 INFO Received cleanup request method=DELETE path=/api/pending_closures14812026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14822026/07/09 07:32:41 INFO Signed narinfos id=1 count=114832026/07/09 07:32:41 INFO Uploading 1 narinfos14842026/07/09 07:32:41 INFO Aborted multipart uploads count=114852026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14862026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14872026/07/09 07:32:41 INFO Uploading 8jrc144nzqjdgsaznw49h34jd3hfwnla-test-file.txt (152B)1488--- PASS: TestMultipartCleanup (0.68s)1489=== NAME TestClientMultipleUploads1490 client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads3827395244/001/store/jcgrm3irr0dmkca0mdl57x303k0a6blb-test-file-2.txt14912026/07/09 07:32:41 INFO Completed upload id=114922026/07/09 07:32:41 INFO Upload complete. (98ms)14932026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14942026/07/09 07:32:41 INFO Signed narinfos id=1 count=114952026/07/09 07:32:41 INFO Uploading 1 narinfos14962026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1497=== NAME TestClientCADerivations1498 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2740151274/001/store/xzbml5pci4ym891i1q15wldzvcqxipdy-ca-test1499 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1500 Compression: zstd1501 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1502 NarSize: 1441503 References: 1504 Deriver: /build/TestClientCADerivations2740151274/001/store/igcc6krs4zkzhv3xbsln3c4218z84dhp-ca-test.drv1505 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1506 client_ca_test.go:185: Checking for realisation files in S3...1507=== NAME TestClientWithDependencies1508 client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies3206384679/001/store/r6gpzv817hm2iags9vvpl8iyb6qsvbfj-test-script1509=== NAME TestClientCADerivations1510 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1511 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache15122026/07/09 07:32:41 INFO Completed upload id=115132026/07/09 07:32:41 INFO Upload complete. (97ms)1514=== NAME TestClientIntegration1515 client_integration_test.go:292: Retrieved narinfo from S3:1516 StorePath: /build/TestClientIntegration3898247121/002/store/8jrc144nzqjdgsaznw49h34jd3hfwnla-test-file.txt1517 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1518 Compression: zstd1519 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11520 NarSize: 1521521 References: 1522 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11523 client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1524 client_integration_test.go:293: Decompressed .ls content (64 bytes):1525 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1526 client_integration_test.go:296: Testing garbage collection...15272026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures15282026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15292026/07/09 07:32:41 INFO Uploading y3k30jq636vwfrad31y2308xr7qvy3g9-pinned-file.txt (128B)15302026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15312026/07/09 07:32:41 INFO Signed narinfos id=1 count=115322026/07/09 07:32:41 INFO Uploading 1 narinfos1533--- PASS: TestGCBugBareHashReferences (0.72s)15342026/07/09 07:32:41 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"15352026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1536=== NAME TestClientWithDependencies1537 client_integration_test.go:595: Found 1 dependencies (including self)15382026/07/09 07:32:41 INFO Completed upload id=115392026/07/09 07:32:41 INFO Upload complete. (96ms)15402026/07/09 07:32:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures15412026/07/09 07:32:41 INFO Garbage collection started15422026/07/09 07:32:41 INFO Aborted multipart uploads count=015432026/07/09 07:32:41 WARN Force mode enabled - objects will be deleted immediately without grace period15442026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures15452026/07/09 07:32:41 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)15462026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15472026/07/09 07:32:41 INFO Signed narinfos id=2 count=115482026/07/09 07:32:41 INFO Uploading 1 narinfos15492026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete15502026/07/09 07:32:41 INFO Completed upload id=215512026/07/09 07:32:41 INFO Upload complete. (82ms)1552=== NAME TestNARDeduplicationMetadataUploadBug1553 metadata_upload_test.go:76: Retrieved narinfo from S3:1554 StorePath: /build/TestNARDeduplicationMetadataUploadBug122429254/001/store/s3jibmrhxzggwc323s8cdjwklx9ybhac-file2.txt1555 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1556 Compression: zstd1557 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1558 NarSize: 1601559 References: 1560 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1561 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1562 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1563 {"version":1,"root":{"type":"regular","size":44}}1564--- PASS: TestNARDeduplicationMetadataUploadBug (0.79s)15652026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures1566=== NAME TestClientCADerivations1567 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1568 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1569 error: binary cache 's3://bucket7?endpoint=http://localhost:40837&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2740151274/001/store'1570 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 115712026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures1572--- PASS: TestClientCADerivations (0.80s)15732026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures15742026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures15752026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15762026/07/09 07:32:41 INFO Uploading r6gpzv817hm2iags9vvpl8iyb6qsvbfj-test-script (136B)15772026/07/09 07:32:41 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)15782026/07/09 07:32:41 INFO Uploading jcgrm3irr0dmkca0mdl57x303k0a6blb-test-file-2.txt (160B)15792026/07/09 07:32:41 INFO Uploading 7qrca2ryq253i90blpnkmcj06l3cyyks-test-file-1.txt (160B)15802026/07/09 07:32:41 INFO Uploading k8gimdjksjvkihzghvjxw38pb06sgdw7-test-file-0.txt (160B)15812026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete15822026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15832026/07/09 07:32:41 INFO Signed narinfos id=1 count=115842026/07/09 07:32:41 INFO Uploading 1 narinfos15852026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15862026/07/09 07:32:41 INFO Signed narinfos id=1 count=115872026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15882026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15892026/07/09 07:32:41 INFO Signed narinfos id=2 count=115902026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign15912026/07/09 07:32:41 INFO Signed narinfos id=3 count=115922026/07/09 07:32:41 INFO Uploading 3 narinfos15932026/07/09 07:32:41 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZjY2ZjMyOWYtYWEyMy00OTFkLWFkODYtOTM3MGE4NGM2NzBkLjM1NmVmODIzLTE0YTQtNDFjNy04YmU4LWI0MjVjNDllMjgwM3gxNzgzNTgyMzYxNDg3ODMzMjc5 parts=1015942026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15952026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15962026/07/09 07:32:41 INFO Completed upload id=115972026/07/09 07:32:41 INFO Upload complete. (59ms)1598=== NAME TestClientWithDependencies1599 client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies3206384679/001/store) requires matching store prefix16002026/07/09 07:32:41 INFO Completed upload id=116012026/07/09 07:32:41 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000016022026/07/09 07:32:41 INFO Completed upload id=116032026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1604--- PASS: TestClientWithDependencies (0.82s)16052026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures16062026/07/09 07:32:41 INFO Completed upload id=216072026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete16082026/07/09 07:32:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures16092026/07/09 07:32:41 INFO Completed upload id=316102026/07/09 07:32:41 INFO Upload complete. (114ms)1611=== NAME TestClientMultipleUploads1612 client_integration_test.go:349: Uploaded 3 paths in 148.459541ms16132026/07/09 07:32:41 INFO Aborted multipart uploads count=01614--- PASS: TestClientMultipleUploads (0.84s)16152026/07/09 07:32:41 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=016162026/07/09 07:32:41 INFO Vacuumed table table=pending_closures16172026/07/09 07:32:41 INFO Vacuumed table table=pending_objects1618=== NAME TestOrphanedObjectsGC1619 orphaned_objects_gc_test.go:290: GC Test Summary:1620 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A16212026/07/09 07:32:41 INFO Vacuumed table table=multipart_uploads1622 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1623 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1624 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1625 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1626--- PASS: TestOrphanedObjectsGC (0.85s)16272026/07/09 07:32:41 INFO Vacuumed table table=closures16282026/07/09 07:32:41 INFO Vacuumed table table=objects16292026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures16302026/07/09 07:32:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16312026/07/09 07:32:41 INFO Uploading m2j6281ymbgpvimnapa7ijqi1jfidl58-unpinned-file.txt (128B)16322026/07/09 07:32:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16332026/07/09 07:32:41 INFO Signed narinfos id=2 count=116342026/07/09 07:32:41 INFO Uploading 1 narinfos16352026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16362026/07/09 07:32:41 INFO Completed upload id=216372026/07/09 07:32:41 INFO Upload complete. (107ms)16382026/07/09 07:32:41 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001639--- PASS: TestService_createPendingClosureHandler (0.88s)16402026/07/09 07:32:41 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=755.211944ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16412026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16422026/07/09 07:32:41 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZjY2ZjMyOWYtYWEyMy00OTFkLWFkODYtOTM3MGE4NGM2NzBkLjBhNjg0YmU5LTZjZTgtNGJjMi1hZjU2LWExOGViYzY5MDkxMXgxNzgzNTgyMzYxNTgwMjYwNDIw parts=1016432026/07/09 07:32:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16442026/07/09 07:32:41 INFO Completed upload id=116452026/07/09 07:32:41 INFO Received create pin request method=POST path=/api/pins/myapp16462026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures16472026/07/09 07:32:41 INFO Received uploads request method=POST path=/api/pending_closures16482026/07/09 07:32:41 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC1570481432/001/store/y3k30jq636vwfrad31y2308xr7qvy3g9-pinned-file.txt narinfo_key=y3k30jq636vwfrad31y2308xr7qvy3g9.narinfo16492026/07/09 07:32:41 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo16502026/07/09 07:32:41 WARN Found objects in DB but missing from S3, will re-upload count=116512026/07/09 07:32:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures16522026/07/09 07:32:41 INFO Garbage collection started1653--- PASS: TestService_verifyS3Integrity (0.91s)16542026/07/09 07:32:41 INFO Aborted multipart uploads count=016552026/07/09 07:32:41 WARN Force mode enabled - objects will be deleted immediately without grace period16562026/07/09 07:32:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16572026/07/09 07:32:41 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZjY2ZjMyOWYtYWEyMy00OTFkLWFkODYtOTM3MGE4NGM2NzBkLjAxMjgyMmVjLWUyOGEtNDQ0Ny1hMGEwLTcyMzc2NjExOTg3MXgxNzgzNTgyMzYxNTgxMDYxMjg2 parts=121658--- PASS: TestRedundantMultipartUpload (0.99s)1659=== NAME TestOrphanedObjectsGCStressTest1660 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1661 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1662--- PASS: TestUploadHandlersRejectOversizedBody (0.15s)1663 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1664 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.08s)1665 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.28s)16662026/07/09 07:32:42 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=016672026/07/09 07:32:42 INFO Vacuumed table table=pending_closures16682026/07/09 07:32:42 INFO Vacuumed table table=pending_objects16692026/07/09 07:32:42 INFO Vacuumed table table=multipart_uploads16702026/07/09 07:32:42 INFO Vacuumed table table=closures16712026/07/09 07:32:42 INFO Vacuumed table table=objects1672=== NAME TestOrphanedObjectsGCStressTest1673 orphaned_objects_gc_test.go:509: Stress test completed successfully:1674 orphaned_objects_gc_test.go:510: - Active objects preserved: 201675 orphaned_objects_gc_test.go:511: - Objects deleted: 2101676 orphaned_objects_gc_test.go:512: - Total GC'd: 2101677--- PASS: TestOrphanedObjectsGCStressTest (1.58s)16782026/07/09 07:32:42 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=016792026/07/09 07:32:42 INFO Vacuumed table table=pending_closures16802026/07/09 07:32:42 INFO Vacuumed table table=pending_objects16812026/07/09 07:32:42 INFO Vacuumed table table=multipart_uploads16822026/07/09 07:32:42 INFO Vacuumed table table=closures16832026/07/09 07:32:42 INFO Vacuumed table table=objects16842026/07/09 07:32:42 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.473772972s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16852026/07/09 07:32:43 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01686=== NAME TestClientIntegration1687 client_integration_test.go:303: Objects in database after GC:1688 client_integration_test.go:303: Successfully deleted all objects with GC --force1689--- PASS: TestClientIntegration (2.74s)16902026/07/09 07:32:43 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01691=== NAME TestPinProtectsFromGC1692 client_integration_test.go:709: Pin successfully protected closure from garbage collection1693--- PASS: TestPinProtectsFromGC (2.92s)1694--- PASS: TestClientErrorHandling (0.01s)1695 --- PASS: TestClientErrorHandling/InvalidStorePath (0.60s)1696 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.71s)1697 --- PASS: TestClientErrorHandling/ServerNotAvailable (3.11s)16982026/07/09 07:32:44 WARN Rate limiter enabled after throttle name=s3-test rate=516992026/07/09 07:32:44 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1700=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1701 throttle_test.go:213: Proxy stats: total=15, throttled=10, completeMultipart=101702 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001703--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (3.50s)1704PASS17052026-07-09 07:32:45.169 UTC [208] LOG: received smart shutdown request17062026-07-09 07:32:45.175 UTC [208] LOG: background worker "logical replication launcher" (PID 218) exited with exit code 117072026-07-09 07:32:45.182 UTC [213] LOG: shutting down17082026-07-09 07:32:45.182 UTC [213] LOG: checkpoint starting: shutdown immediate17092026-07-09 07:32:46.355 UTC [213] LOG: checkpoint complete: wrote 9398 buffers (57.4%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 12 recycled; write=0.230 s, sync=0.930 s, total=1.173 s; sync files=14509, longest=0.011 s, average=0.001 s; distance=199422 kB, estimate=199422 kB; lsn=0/DA1EAE8, redo lsn=0/DA1EAE817102026-07-09 07:32:46.475 UTC [208] LOG: database system is shut down1711Running OIDC tests...1712=== RUN TestGlobMatch1713=== PAUSE TestGlobMatch1714=== RUN TestAudienceForIssuer1715=== PAUSE TestAudienceForIssuer1716=== RUN TestValidateToken_ValidToken1717=== PAUSE TestValidateToken_ValidToken1718=== RUN TestValidateToken_WrongAudience1719=== PAUSE TestValidateToken_WrongAudience1720=== RUN TestValidateToken_Expired1721=== PAUSE TestValidateToken_Expired1722=== RUN TestValidateToken_BoundClaimsMismatch1723=== PAUSE TestValidateToken_BoundClaimsMismatch1724=== RUN TestValidateToken_BoundSubjectMismatch1725=== PAUSE TestValidateToken_BoundSubjectMismatch1726=== RUN TestValidateToken_MultipleProviders1727=== PAUSE TestValidateToken_MultipleProviders1728=== RUN TestValidateToken_NoMatchingProvider1729=== PAUSE TestValidateToken_NoMatchingProvider1730=== CONT TestGlobMatch1731=== RUN TestGlobMatch/foo_foo1732=== CONT TestValidateToken_WrongAudience1733=== CONT TestValidateToken_BoundClaimsMismatch1734=== CONT TestValidateToken_ValidToken1735=== PAUSE TestGlobMatch/foo_foo1736=== RUN TestGlobMatch/foo_bar1737=== PAUSE TestGlobMatch/foo_bar1738=== RUN TestGlobMatch/*_1739=== PAUSE TestGlobMatch/*_1740=== RUN TestGlobMatch/*_anything1741=== CONT TestAudienceForIssuer1742=== CONT TestValidateToken_NoMatchingProvider1743=== CONT TestValidateToken_Expired1744=== CONT TestValidateToken_BoundSubjectMismatch1745=== PAUSE TestGlobMatch/*_anything1746=== CONT TestValidateToken_MultipleProviders1747--- PASS: TestAudienceForIssuer (0.00s)1748=== RUN TestGlobMatch/foo*_foo1749=== PAUSE TestGlobMatch/foo*_foo1750=== RUN TestGlobMatch/foo*_foobar1751=== PAUSE TestGlobMatch/foo*_foobar1752=== RUN TestGlobMatch/foo*_bar1753=== PAUSE TestGlobMatch/foo*_bar1754=== RUN TestGlobMatch/*bar_bar1755=== PAUSE TestGlobMatch/*bar_bar1756=== RUN TestGlobMatch/*bar_foobar1757=== PAUSE TestGlobMatch/*bar_foobar1758=== RUN TestGlobMatch/*bar_foo1759=== PAUSE TestGlobMatch/*bar_foo1760=== RUN TestGlobMatch/foo*bar_foobar1761=== PAUSE TestGlobMatch/foo*bar_foobar1762=== RUN TestGlobMatch/foo*bar_foo123bar1763=== PAUSE TestGlobMatch/foo*bar_foo123bar1764=== RUN TestGlobMatch/foo*bar_foobarbaz1765=== PAUSE TestGlobMatch/foo*bar_foobarbaz1766=== RUN TestGlobMatch/*/*_foo/bar1767=== PAUSE TestGlobMatch/*/*_foo/bar1768=== RUN TestGlobMatch/*/*_foo1769=== PAUSE TestGlobMatch/*/*_foo1770=== RUN TestGlobMatch/refs/heads/*_refs/heads/main1771=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1772=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.01773=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01774=== RUN TestGlobMatch/refs/*/main_refs/heads/main1775=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main1776=== RUN TestGlobMatch/fo?_foo1777=== PAUSE TestGlobMatch/fo?_foo1778=== RUN TestGlobMatch/fo?_fo1779=== PAUSE TestGlobMatch/fo?_fo1780=== RUN TestGlobMatch/fo?_fooo1781=== PAUSE TestGlobMatch/fo?_fooo1782=== RUN TestGlobMatch/?oo_foo1783=== PAUSE TestGlobMatch/?oo_foo1784=== RUN TestGlobMatch/?oo_boo1785=== PAUSE TestGlobMatch/?oo_boo1786=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1787=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1789=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1790=== CONT TestGlobMatch/foo_foo1791=== CONT TestGlobMatch/*bar_foobar1792=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1793=== CONT TestGlobMatch/foo*bar_foobarbaz1794=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1795=== CONT TestGlobMatch/foo*bar_foo123bar1796=== CONT TestGlobMatch/?oo_foo1797=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.01798=== CONT TestGlobMatch/refs/heads/*_refs/heads/main1799=== CONT TestGlobMatch/fo?_fooo1800=== CONT TestGlobMatch/*/*_foo1801=== CONT TestGlobMatch/fo?_fo1802=== CONT TestGlobMatch/*/*_foo/bar1803=== CONT TestGlobMatch/fo?_foo1804=== CONT TestGlobMatch/refs/*/main_refs/heads/main1805=== CONT TestGlobMatch/*bar_foo1806=== CONT TestGlobMatch/foo*_bar1807=== CONT TestGlobMatch/foo*bar_foobar1808=== CONT TestGlobMatch/*bar_bar1809=== CONT TestGlobMatch/*_1810=== CONT TestGlobMatch/foo_bar1811=== CONT TestGlobMatch/*_anything1812=== CONT TestGlobMatch/foo*_foobar1813=== CONT TestGlobMatch/foo*_foo1814=== CONT TestGlobMatch/?oo_boo1815--- PASS: TestGlobMatch (0.01s)1816 --- PASS: TestGlobMatch/foo_foo (0.00s)1817 --- PASS: TestGlobMatch/*bar_foobar (0.00s)1818 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1819 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1820 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1821 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1822 --- PASS: TestGlobMatch/?oo_foo (0.00s)1823 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1824 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1825 --- PASS: TestGlobMatch/fo?_fooo (0.00s)1826 --- PASS: TestGlobMatch/*/*_foo (0.00s)1827 --- PASS: TestGlobMatch/fo?_fo (0.00s)1828 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1829 --- PASS: TestGlobMatch/fo?_foo (0.00s)1830 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1831 --- PASS: TestGlobMatch/*bar_foo (0.00s)1832 --- PASS: TestGlobMatch/foo*_bar (0.00s)1833 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1834 --- PASS: TestGlobMatch/*bar_bar (0.00s)1835 --- PASS: TestGlobMatch/*_ (0.00s)1836 --- PASS: TestGlobMatch/foo_bar (0.00s)1837 --- PASS: TestGlobMatch/*_anything (0.00s)1838 --- PASS: TestGlobMatch/foo*_foobar (0.00s)1839 --- PASS: TestGlobMatch/foo*_foo (0.00s)1840 --- PASS: TestGlobMatch/?oo_boo (0.00s)18412026/07/09 07:32:47 INFO OIDC provider initialized name=test18422026/07/09 07:32:47 INFO OIDC provider initialized name=test18432026/07/09 07:32:47 INFO OIDC provider initialized name=provider118442026/07/09 07:32:47 INFO OIDC provider initialized name=test18452026/07/09 07:32:47 INFO OIDC provider initialized name=provider118462026/07/09 07:32:47 INFO OIDC provider initialized name=test18472026/07/09 07:32:47 INFO OIDC provider initialized name=test18482026/07/09 07:32:47 INFO OIDC provider initialized name=provider21849--- PASS: TestValidateToken_Expired (0.01s)1850--- PASS: TestValidateToken_NoMatchingProvider (0.01s)1851--- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1852--- PASS: TestValidateToken_ValidToken (0.02s)1853--- PASS: TestValidateToken_WrongAudience (0.02s)1854--- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)1855--- PASS: TestValidateToken_MultipleProviders (0.02s)1856PASS1857Running hook tests...1858=== RUN TestSendPathsEmpty1859=== PAUSE TestSendPathsEmpty1860=== RUN TestQueueEnqueueAndFetch1861=== PAUSE TestQueueEnqueueAndFetch1862=== RUN TestQueueDeduplication1863=== PAUSE TestQueueDeduplication1864=== RUN TestQueueRemove1865=== PAUSE TestQueueRemove1866=== RUN TestQueueFetchBatchLimit1867=== PAUSE TestQueueFetchBatchLimit1868=== RUN TestQueueFetchRemoveLifecycle1869=== PAUSE TestQueueFetchRemoveLifecycle1870=== RUN TestQueueConcurrentWriters1871=== PAUSE TestQueueConcurrentWriters1872=== RUN TestServerClientIntegration1873=== PAUSE TestServerClientIntegration1874=== RUN TestServerQueueError1875=== PAUSE TestServerQueueError1876=== RUN TestGetListenerSocketActivation1877 server_test.go:210: === RUN TestGetListenerSocketActivation1878 --- PASS: TestGetListenerSocketActivation (0.00s)1879 PASS1880 1881--- PASS: TestGetListenerSocketActivation (0.01s)1882=== RUN TestWorkerUploadsAndRemoves1883=== PAUSE TestWorkerUploadsAndRemoves1884=== RUN TestWorkerSkipsGCdPaths1885=== PAUSE TestWorkerSkipsGCdPaths1886=== RUN TestWorkerPrunesClosureDeps1887=== PAUSE TestWorkerPrunesClosureDeps1888=== CONT TestSendPathsEmpty1889=== CONT TestQueueConcurrentWriters1890=== CONT TestServerQueueError1891=== CONT TestWorkerUploadsAndRemoves1892--- PASS: TestSendPathsEmpty (0.00s)1893=== CONT TestWorkerPrunesClosureDeps1894=== CONT TestQueueFetchRemoveLifecycle1895=== CONT TestQueueFetchBatchLimit1896=== CONT TestWorkerSkipsGCdPaths1897=== CONT TestQueueRemove1898=== CONT TestQueueDeduplication1899=== CONT TestServerClientIntegration1900=== CONT TestQueueEnqueueAndFetch19012026/07/09 07:32:47 ERROR Failed to queue paths error="permission denied" count=11902--- PASS: TestServerQueueError (0.01s)1903--- PASS: TestServerClientIntegration (0.00s)1904--- PASS: TestQueueDeduplication (0.01s)1905--- PASS: TestQueueFetchBatchLimit (0.01s)19062026/07/09 07:32:47 INFO Upload queue status pending=219072026/07/09 07:32:47 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2972718813/002/nonexistent19082026/07/09 07:32:47 INFO Upload queue status pending=219092026/07/09 07:32:47 INFO Uploading batch count=219102026/07/09 07:32:47 INFO Upload queue status pending=21911--- PASS: TestQueueRemove (0.01s)19122026/07/09 07:32:47 INFO Uploading batch count=119132026/07/09 07:32:47 INFO Uploading batch count=11914--- PASS: TestQueueEnqueueAndFetch (0.01s)1915--- PASS: TestQueueFetchRemoveLifecycle (0.01s)1916--- PASS: TestWorkerSkipsGCdPaths (0.06s)1917--- PASS: TestWorkerPrunesClosureDeps (0.06s)1918--- PASS: TestWorkerUploadsAndRemoves (0.07s)1919--- PASS: TestQueueConcurrentWriters (0.22s)1920PASS

succeeded niks3-go-unit-tests aarch64-linux.go-unit-tests · build #94 · raw

succeeded `niks3-go-unit-tests` `aarch64-linux.go-unit-tests` · build #94 · raw