niks3-go-unit-tests
checks.aarch64-darwin.go-unit-tests
· build #247
· raw
1Running client tests...2=== RUN TestDoServerRequestAttachesToken3=== PAUSE TestDoServerRequestAttachesToken4=== RUN TestRegisterUploadedObjectReusesConnections5=== PAUSE TestRegisterUploadedObjectReusesConnections6=== RUN TestCaseHackSuffix7=== PAUSE TestCaseHackSuffix8=== RUN TestFilterOversizedClosures9=== PAUSE TestFilterOversizedClosures10=== RUN TestUploadMultipart_PartsInParallel11=== PAUSE TestUploadMultipart_PartsInParallel12=== RUN TestPartSizeForNAR13=== PAUSE TestPartSizeForNAR14=== RUN TestUploadMultipart_SupersededByPeer15=== PAUSE TestUploadMultipart_SupersededByPeer16=== RUN TestDumpPathCaseHackMatchesNix17--- PASS: TestDumpPathCaseHackMatchesNix (0.05s)18=== RUN TestDumpPathCaseHackCollision19--- PASS: TestDumpPathCaseHackCollision (0.00s)20=== RUN TestDumpPathMatchesNix21=== PAUSE TestDumpPathMatchesNix22=== RUN TestDumpPathSingleFile23=== PAUSE TestDumpPathSingleFile24=== RUN TestDumpPathWriterError25=== PAUSE TestDumpPathWriterError26=== RUN TestEncodeNixBase3227=== PAUSE TestEncodeNixBase3228=== RUN TestEncodeNixBase32WithRealHash29=== PAUSE TestEncodeNixBase32WithRealHash30=== RUN TestConvertHashToNix3231=== PAUSE TestConvertHashToNix3232=== RUN TestGetStorePathHash33=== PAUSE TestGetStorePathHash34=== RUN TestPathInfoHashCompatibility35=== PAUSE TestPathInfoHashCompatibility36=== RUN TestParsePathInfoJSON37=== PAUSE TestParsePathInfoJSON38=== RUN TestParsePathInfoJSONMultiplePaths39=== PAUSE TestParsePathInfoJSONMultiplePaths40=== RUN TestPathInfoCACompatibility41=== PAUSE TestPathInfoCACompatibility42=== RUN TestRateLimiterFeedback43=== PAUSE TestRateLimiterFeedback44=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess45=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess46=== RUN TestResolveStorePath47=== PAUSE TestResolveStorePath48=== RUN TestDoWithRetry_BodyReplayedViaGetBody49=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody50=== RUN TestShellSplit51=== PAUSE TestShellSplit52=== RUN TestShellSplitErrors53=== PAUSE TestShellSplitErrors54=== RUN TestStreamPushReportsEveryPath55=== PAUSE TestStreamPushReportsEveryPath56=== RUN TestStreamPushBatchesUnderLoad57=== PAUSE TestStreamPushBatchesUnderLoad58=== RUN TestStreamPushIsolatesFailures59=== PAUSE TestStreamPushIsolatesFailures60=== RUN TestStreamPushGivesUpOnDeadServer61=== PAUSE TestStreamPushGivesUpOnDeadServer62=== RUN TestStreamPushRequestLine63=== PAUSE TestStreamPushRequestLine64=== RUN TestStreamPushReportsSignatures65=== PAUSE TestStreamPushReportsSignatures66=== RUN TestSetClientTLS67=== PAUSE TestSetClientTLS68=== RUN TestSetClientTLSDoesNotMutateDefaultTransport69=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport70=== RUN TestSetClientTLSErrors71=== PAUSE TestSetClientTLSErrors72=== RUN TestStaticToken73=== PAUSE TestStaticToken74=== RUN TestFileTokenReadsAndCaches75=== PAUSE TestFileTokenReadsAndCaches76=== RUN TestFileTokenMissing77=== PAUSE TestFileTokenMissing78=== RUN TestFileTokenEmpty79=== PAUSE TestFileTokenEmpty80=== RUN TestScriptTokenNoExpiryRerunsEveryCall81=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall82=== RUN TestScriptTokenCachesUntilRefresh83=== PAUSE TestScriptTokenCachesUntilRefresh84=== RUN TestScriptTokenEmptyToken85=== PAUSE TestScriptTokenEmptyToken86=== RUN TestScriptTokenBadJSON87=== PAUSE TestScriptTokenBadJSON88=== RUN TestScriptTokenScriptFails89=== PAUSE TestScriptTokenScriptFails90=== RUN TestScriptTokenEmptyCommand91=== PAUSE TestScriptTokenEmptyCommand92=== CONT TestDoServerRequestAttachesToken93=== CONT TestShellSplit94=== CONT TestDoWithRetry_BodyReplayedViaGetBody95=== CONT TestUploadMultipart_SupersededByPeer96=== RUN TestUploadMultipart_SupersededByPeer/exists97=== PAUSE TestUploadMultipart_SupersededByPeer/exists98--- PASS: TestShellSplit (0.00s)99=== CONT TestEncodeNixBase32WithRealHash100--- PASS: TestEncodeNixBase32WithRealHash (0.00s)101=== CONT TestScriptTokenNoExpiryRerunsEveryCall102=== CONT TestScriptTokenEmptyCommand103--- PASS: TestScriptTokenEmptyCommand (0.00s)104=== CONT TestFileTokenEmpty105=== CONT TestScriptTokenScriptFails106=== CONT TestScriptTokenBadJSON107=== CONT TestScriptTokenEmptyToken108=== CONT TestScriptTokenCachesUntilRefresh109=== CONT TestFilterOversizedClosures110=== RUN TestFilterOversizedClosures/no_limit_keeps_everything111=== RUN TestUploadMultipart_SupersededByPeer/missing112=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything113=== PAUSE TestUploadMultipart_SupersededByPeer/missing114=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped115=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped116=== CONT TestFileTokenMissing117=== RUN TestFilterOversizedClosures/all_closures_skipped118=== PAUSE TestFilterOversizedClosures/all_closures_skipped119=== CONT TestFileTokenReadsAndCaches120--- PASS: TestFileTokenMissing (0.00s)121=== CONT TestSetClientTLSErrors122--- PASS: TestFileTokenEmpty (0.00s)123=== CONT TestStaticToken124--- PASS: TestStaticToken (0.00s)125=== CONT TestSetClientTLSDoesNotMutateDefaultTransport126--- PASS: TestFileTokenReadsAndCaches (0.00s)127=== CONT TestSetClientTLS1282026/09/22 08:50:32 WARN Rate limiter enabled after throttle name=server-test rate=51292026/09/22 08:50:32 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52950130--- PASS: TestDoServerRequestAttachesToken (0.01s)131=== CONT TestStreamPushReportsSignatures1322026/09/22 08:50:32 WARN Rate limiter backed off name=server-test rate=51332026/09/22 08:50:32 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52950134--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)135=== CONT TestStreamPushRequestLine136=== RUN TestSetClientTLSErrors/missing_cert_file137=== PAUSE TestSetClientTLSErrors/missing_cert_file138=== RUN TestSetClientTLSErrors/missing_key_file139=== PAUSE TestSetClientTLSErrors/missing_key_file140=== RUN TestSetClientTLSErrors/missing_ca_file141=== PAUSE TestSetClientTLSErrors/missing_ca_file142=== RUN TestSetClientTLSErrors/invalid_ca_file143=== PAUSE TestSetClientTLSErrors/invalid_ca_file144=== CONT TestStreamPushGivesUpOnDeadServer145--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)146=== CONT TestStreamPushIsolatesFailures1472026/09/22 08:50:32 ERROR Upload failed error="connection refused" count=201482026/09/22 08:50:32 ERROR Server seems unavailable, giving up on batch untried=171492026/09/22 08:50:32 ERROR Upload failed error="bad path" count=31502026/09/22 08:50:32 ERROR Upload failed error=boom count=11512026/09/22 08:50:32 ERROR Upload failed error=boom count=1152--- PASS: TestStreamPushIsolatesFailures (0.00s)153--- PASS: TestStreamPushReportsSignatures (0.00s)154--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)155=== CONT TestStreamPushBatchesUnderLoad156=== CONT TestStreamPushReportsEveryPath157=== CONT TestShellSplitErrors158--- PASS: TestShellSplitErrors (0.00s)159=== CONT TestDumpPathWriterError160--- PASS: TestStreamPushReportsEveryPath (0.00s)161=== CONT TestEncodeNixBase32162=== RUN TestEncodeNixBase32/test_string_hash163=== PAUSE TestEncodeNixBase32/test_string_hash164=== RUN TestEncodeNixBase32/empty_input165=== PAUSE TestEncodeNixBase32/empty_input166=== CONT TestParsePathInfoJSONMultiplePaths167=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths168=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths169=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths170=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths171=== CONT TestResolveStorePath172=== RUN TestSetClientTLS/rejects_connection_without_client_cert173=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert174=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA175=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA176=== RUN TestSetClientTLS/preserves_debug_logging_transport177=== PAUSE TestSetClientTLS/preserves_debug_logging_transport178=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess1792026/09/22 08:50:32 WARN Rate limiter enabled after throttle name=server-test rate=5180--- PASS: TestScriptTokenScriptFails (0.01s)181=== CONT TestRateLimiterFeedback182=== RUN TestRateLimiterFeedback/429_enables_limiter183=== PAUSE TestRateLimiterFeedback/429_enables_limiter184=== RUN TestRateLimiterFeedback/503_enables_limiter185=== PAUSE TestRateLimiterFeedback/503_enables_limiter186=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter187=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter188=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter189=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter190=== CONT TestPathInfoCACompatibility191=== RUN TestPathInfoCACompatibility/null_ca_field192=== PAUSE TestPathInfoCACompatibility/null_ca_field193=== RUN TestPathInfoCACompatibility/old_string_format_-_text194=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text195=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive196=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive197=== RUN TestPathInfoCACompatibility/new_structured_format_-_text198=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text199=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method200=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method201=== CONT TestPartSizeForNAR202=== RUN TestPartSizeForNAR/zero_stays_at_minimum203=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum204=== RUN TestPartSizeForNAR/small_stays_at_minimum205=== PAUSE TestPartSizeForNAR/small_stays_at_minimum206=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum207=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum208=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts209=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts210=== RUN TestPartSizeForNAR/1_TiB211=== PAUSE TestPartSizeForNAR/1_TiB212=== RUN TestPartSizeForNAR/5_TiB_S3_max_object213--- PASS: TestResolveStorePath (0.00s)214=== CONT TestDumpPathSingleFile215=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object216=== RUN TestPartSizeForNAR/capped_at_5_GiB217=== PAUSE TestPartSizeForNAR/capped_at_5_GiB218=== CONT TestUploadMultipart_PartsInParallel219--- PASS: TestScriptTokenBadJSON (0.01s)220--- PASS: TestScriptTokenEmptyToken (0.01s)221=== CONT TestParsePathInfoJSON222=== CONT TestPathInfoHashCompatibility223=== RUN TestParsePathInfoJSON/Nix_format224=== PAUSE TestParsePathInfoJSON/Nix_format225=== RUN TestParsePathInfoJSON/Lix_format226=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)227=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)228=== PAUSE TestParsePathInfoJSON/Lix_format229=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon230=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon231=== RUN TestParsePathInfoJSON/empty_input232=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI233=== PAUSE TestParsePathInfoJSON/empty_input234=== RUN TestParsePathInfoJSON/whitespace_only235=== PAUSE TestParsePathInfoJSON/whitespace_only236=== RUN TestParsePathInfoJSON/invalid_JSON237=== PAUSE TestParsePathInfoJSON/invalid_JSON238=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI239=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512240=== CONT TestGetStorePathHash241=== RUN TestGetStorePathHash/valid_store_path242=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512243=== PAUSE TestGetStorePathHash/valid_store_path244=== CONT TestDumpPathMatchesNix245=== RUN TestGetStorePathHash/basename_without_hyphen_should_error246=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error247=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error248=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error249=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error250=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error251=== CONT TestCaseHackSuffix252--- PASS: TestStreamPushRequestLine (0.01s)253=== CONT TestConvertHashToNix32254=== RUN TestConvertHashToNix32/SRI_format_to_Nix32255=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32256=== RUN TestConvertHashToNix32/already_Nix32_format257=== PAUSE TestConvertHashToNix32/already_Nix32_format258=== RUN TestConvertHashToNix32/invalid_format259=== PAUSE TestConvertHashToNix32/invalid_format260=== CONT TestRegisterUploadedObjectReusesConnections261--- PASS: TestScriptTokenCachesUntilRefresh (0.03s)262=== CONT TestUploadMultipart_SupersededByPeer/exists263=== CONT TestUploadMultipart_SupersededByPeer/missing264--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)265=== CONT TestFilterOversizedClosures/all_closures_skipped2662026/09/22 08:50:32 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper nar_size=100 max_nar_size=50267--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)268 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)269 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)270=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped271=== CONT TestFilterOversizedClosures/no_limit_keeps_everything2722026/09/22 08:50:32 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb-vm-image nar_size=5000 max_nar_size=2000273--- PASS: TestFilterOversizedClosures (0.00s)274 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)275 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)276 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)277=== CONT TestSetClientTLSErrors/missing_cert_file278=== CONT TestSetClientTLSErrors/invalid_ca_file279=== CONT TestSetClientTLSErrors/missing_ca_file280=== CONT TestSetClientTLSErrors/missing_key_file281=== CONT TestEncodeNixBase32/test_string_hash282=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths283=== CONT TestEncodeNixBase32/empty_input284--- PASS: TestEncodeNixBase32 (0.00s)285 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)286 --- PASS: TestEncodeNixBase32/empty_input (0.00s)287=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths288--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)289 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)290 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)291=== CONT TestSetClientTLS/rejects_connection_without_client_cert292--- PASS: TestSetClientTLSErrors (0.00s)293 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)294 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)295 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)296 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)297=== CONT TestSetClientTLS/preserves_debug_logging_transport298=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA299--- PASS: TestRegisterUploadedObjectReusesConnections (0.02s)300=== CONT TestRateLimiterFeedback/429_enables_limiter301=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter3022026/09/22 08:50:32 WARN Rate limiter enabled after throttle name=server-test rate=53032026/09/22 08:50:32 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:530343042026/09/22 08:50:32 WARN Rate limiter backed off name=server-test rate=5305=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter306=== CONT TestRateLimiterFeedback/503_enables_limiter3072026/09/22 08:50:32 WARN Rate limiter enabled after throttle name=server-test rate=53082026/09/22 08:50:32 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:53039309=== CONT TestPathInfoCACompatibility/null_ca_field310=== CONT TestPathInfoCACompatibility/new_structured_format_-_text3112026/09/22 08:50:32 WARN Rate limiter backed off name=server-test rate=5312--- PASS: TestRateLimiterFeedback (0.00s)313 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)314 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)315 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)316 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)317=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method318=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive319=== CONT TestPathInfoCACompatibility/old_string_format_-_text320=== CONT TestPartSizeForNAR/zero_stays_at_minimum321--- PASS: TestPathInfoCACompatibility (0.00s)322 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)323 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)324 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)325 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)326 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)327=== CONT TestPartSizeForNAR/5_TiB_S3_max_object328=== CONT TestPartSizeForNAR/1_TiB329=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts330=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum331=== CONT TestPartSizeForNAR/small_stays_at_minimum332=== CONT TestPartSizeForNAR/capped_at_5_GiB333--- PASS: TestPartSizeForNAR (0.00s)334 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)335 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)336 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)337 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)338 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)339 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)340 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)341=== CONT TestParsePathInfoJSON/Nix_format342=== CONT TestParsePathInfoJSON/whitespace_only343=== CONT TestParsePathInfoJSON/empty_input344=== CONT TestParsePathInfoJSON/invalid_JSON345=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)346=== CONT TestParsePathInfoJSON/Lix_format347=== CONT TestGetStorePathHash/valid_store_path348--- PASS: TestParsePathInfoJSON (0.00s)349 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)350 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)351 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)352 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)353 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)354=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512355=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error356=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error357=== CONT TestGetStorePathHash/basename_without_hyphen_should_error358=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI359=== CONT TestConvertHashToNix32/SRI_format_to_Nix32360=== CONT TestConvertHashToNix32/invalid_format361--- PASS: TestGetStorePathHash (0.00s)362 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)363 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)364 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)365 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)366=== CONT TestConvertHashToNix32/already_Nix32_format367=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon368--- PASS: TestConvertHashToNix32 (0.00s)369 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)370 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)371 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)372--- PASS: TestPathInfoHashCompatibility (0.00s)373 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)374 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)375 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)376 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)3772026/09/22 08:50:32 http: TLS handshake error from 127.0.0.1:53031: remote error: tls: bad certificate378--- PASS: TestSetClientTLS (0.00s)379 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)380 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)381 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)382--- PASS: TestDumpPathWriterError (0.04s)383--- PASS: TestDumpPathSingleFile (0.05s)384--- PASS: TestCaseHackSuffix (0.05s)385--- PASS: TestDumpPathMatchesNix (0.06s)386--- PASS: TestStreamPushBatchesUnderLoad (0.10s)387--- PASS: TestUploadMultipart_PartsInParallel (0.61s)388--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)389PASS390Running server tests...391The files belonging to this database system will be owned by user "_nixbld1".392This user must also own the server process.393394The database cluster will be initialized with locale "C".395The default database encoding has accordingly been set to "SQL_ASCII".396The default text search configuration will be set to "english".397398Data page checksums are enabled.399400creating directory /nix/var/nix/builds/nix-73934-291241723/postgres2900519540/data ... ok401creating subdirectories ... ok402selecting dynamic shared memory implementation ... posix403selecting default "max_connections" ... 100404selecting default "shared_buffers" ... 128MB405selecting default time zone ... UTC406creating configuration files ... ok407running bootstrap script ... ok408performing post-bootstrap initialization ... ok409syncing data to disk ... ok410411initdb: warning: enabling "trust" authentication for local connections412initdb: 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.413414Success. You can now start the database server using:415416 pg_ctl -D /nix/var/nix/builds/nix-73934-291241723/postgres2900519540/data -l logfile start417418/nix/var/nix/builds/nix-73934-291241723/postgres2900519540:5432 - no response4192026-09-22 08:50:33.764 UTC [74022] LOG: starting PostgreSQL 18.6 on aarch64-apple-darwin25.6.0, compiled by clang version 21.1.8, 64-bit4202026-09-22 08:50:33.764 UTC [74022] LOG: listening on Unix socket "/nix/var/nix/builds/nix-73934-291241723/postgres2900519540/.s.PGSQL.5432"4212026-09-22 08:50:33.766 UTC [74029] LOG: database system was shut down at 2026-09-22 08:50:33 UTC4222026-09-22 08:50:33.767 UTC [74022] LOG: database system is ready to accept connections423/nix/var/nix/builds/nix-73934-291241723/postgres2900519540:5432 - accepting connections424{"timestamp":"2026-09-22T08:50:33.977233Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"d53e85f5-d32e-4349-b494-6b8cd52ec2ab","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":463,"threadName":"rustfs-worker","threadId":"ThreadId(5)"}425=== RUN TestService_AuthMiddleware426=== PAUSE TestService_AuthMiddleware427=== RUN TestService_AuthMiddleware_MTLSProxyHeader428=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader429=== RUN TestService_AuthMiddleware_MTLSBoundSubjects430=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects431=== RUN TestService_ReadAuthMiddleware432=== PAUSE TestService_ReadAuthMiddleware433=== RUN TestService_AuthMiddleware_OIDC434=== PAUSE TestService_AuthMiddleware_OIDC435=== RUN TestService_RequireScope_OIDC436=== PAUSE TestService_RequireScope_OIDC437=== RUN TestService_ReadScope_PublicByDefault438=== PAUSE TestService_ReadScope_PublicByDefault439=== RUN TestCacheConfigHandler440=== PAUSE TestCacheConfigHandler441=== RUN TestCacheStatsHandler442=== PAUSE TestCacheStatsHandler443=== RUN TestClientCADerivations444=== PAUSE TestClientCADerivations445=== RUN TestClientErrorHandling446=== PAUSE TestClientErrorHandling447=== RUN TestClientIntegration448=== PAUSE TestClientIntegration449=== RUN TestClientMultipleUploads450=== PAUSE TestClientMultipleUploads451=== RUN TestClientWithDependencies452=== PAUSE TestClientWithDependencies453=== RUN TestClientSharedPathCommittedMidPush454=== PAUSE TestClientSharedPathCommittedMidPush455=== RUN TestPinProtectsFromGC456=== PAUSE TestPinProtectsFromGC457=== RUN TestClientReportsSignatures458=== PAUSE TestClientReportsSignatures459=== RUN TestResolveDBConnectionString460=== PAUSE TestResolveDBConnectionString461=== RUN TestLeadElectsOneAndHandsOver462=== PAUSE TestLeadElectsOneAndHandsOver463=== RUN TestLeadIncumbentWinsAfterRestart4642026-09-22 08:50:34.176 UTC [74061] ERROR: relation "goose_db_version" does not exist at character 364652026-09-22 08:50:34.176 UTC [74061] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4662026/09/22 08:50:34 OK 20241026095416_initial_model.sql (3.22ms)4672026/09/22 08:50:34 OK 20251210153512_drop_unused_gin_index.sql (349.79µs)4682026/09/22 08:50:34 OK 20251218171726_add_pins.sql (781.67µs)4692026/09/22 08:50:34 OK 20260628120000_add_object_size_and_stats.sql (790.08µs)4702026/09/22 08:50:34 OK 20260905000000_add_claims.sql (864.21µs)4712026/09/22 08:50:34 OK 20260920000000_drop_claims.sql (531.83µs)4722026/09/22 08:50:34 goose: successfully migrated database to version: 202609200000004732026/09/22 08:50:34 OK 1_commit_pending_closure.sql (821.96µs)4742026/09/22 08:50:34 OK 2_object_stats_trigger.sql (180.71µs)4752026/09/22 08:50:34 goose: up to current file version: 24762026/09/22 08:50:34 INFO lead: acquired remote=192.0.2.1:12344772026/09/22 08:50:34 INFO lead: released remote=192.0.2.1:12344782026/09/22 08:50:34 INFO lead: acquired remote=192.0.2.1:12344792026/09/22 08:50:34 INFO lead: released remote=192.0.2.1:1234480--- PASS: TestLeadIncumbentWinsAfterRestart (0.81s)481=== RUN TestLeadEndsOnShutdown482=== PAUSE TestLeadEndsOnShutdown483=== RUN TestGCAdvisoryLockBlocksConcurrentRun4842026-09-22 08:50:34.967 UTC [74072] ERROR: relation "goose_db_version" does not exist at character 364852026-09-22 08:50:34.967 UTC [74072] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4862026/09/22 08:50:34 OK 20241026095416_initial_model.sql (3.61ms)4872026/09/22 08:50:34 OK 20251210153512_drop_unused_gin_index.sql (403.75µs)4882026/09/22 08:50:34 OK 20251218171726_add_pins.sql (1.12ms)4892026/09/22 08:50:34 OK 20260628120000_add_object_size_and_stats.sql (889.63µs)4902026/09/22 08:50:34 OK 20260905000000_add_claims.sql (994.67µs)4912026/09/22 08:50:34 OK 20260920000000_drop_claims.sql (639.83µs)4922026/09/22 08:50:34 goose: successfully migrated database to version: 202609200000004932026/09/22 08:50:34 OK 1_commit_pending_closure.sql (916.5µs)4942026/09/22 08:50:34 OK 2_object_stats_trigger.sql (220.79µs)4952026/09/22 08:50:34 goose: up to current file version: 2496--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.14s)497=== RUN TestGCBugBareHashReferences498=== PAUSE TestGCBugBareHashReferences499=== RUN TestGCMetrics500=== PAUSE TestGCMetrics501=== RUN TestGCTaskStore_StartNew502=== PAUSE TestGCTaskStore_StartNew503=== RUN TestGCTaskStore_DeduplicateSameParams504=== PAUSE TestGCTaskStore_DeduplicateSameParams505=== RUN TestGCTaskStore_ConflictDifferentParams506=== PAUSE TestGCTaskStore_ConflictDifferentParams507=== RUN TestGCTaskStore_GetEmpty508=== PAUSE TestGCTaskStore_GetEmpty509=== RUN TestGCTaskStore_GetReturnsLatest510=== PAUSE TestGCTaskStore_GetReturnsLatest511=== RUN TestGCTaskStore_CompletedAllowsNewTask512=== PAUSE TestGCTaskStore_CompletedAllowsNewTask513=== RUN TestGCTaskStore_PhaseUpdates514=== PAUSE TestGCTaskStore_PhaseUpdates515=== RUN TestGCTaskStore_Fail516=== PAUSE TestGCTaskStore_Fail517=== RUN TestGracefulShutdownDrainsInflight518=== PAUSE TestGracefulShutdownDrainsInflight519=== RUN TestService_healthCheckHandler520=== PAUSE TestService_healthCheckHandler521=== RUN TestService_readinessHandler522=== PAUSE TestService_readinessHandler523=== RUN TestGenerateLandingPage524=== PAUSE TestGenerateLandingPage525=== RUN TestCacheConfigHandlerMaxNarSize526=== PAUSE TestCacheConfigHandlerMaxNarSize527=== RUN TestCreatePendingClosureRejectsOversizedNAR528=== PAUSE TestCreatePendingClosureRejectsOversizedNAR529=== RUN TestNARDeduplicationMetadataUploadBug530=== PAUSE TestNARDeduplicationMetadataUploadBug531=== RUN TestMetricsInventory532=== PAUSE TestMetricsInventory533=== RUN TestService_NativeMTLS534=== PAUSE TestService_NativeMTLS535=== RUN TestServerTLSConfig536=== PAUSE TestServerTLSConfig537=== RUN TestMultipartCleanup538=== PAUSE TestMultipartCleanup539=== RUN TestObjectStatsTrigger540=== PAUSE TestObjectStatsTrigger541=== RUN TestOrphanedObjectsGC542=== PAUSE TestOrphanedObjectsGC543=== RUN TestOrphanedObjectsGCStressTest544=== PAUSE TestOrphanedObjectsGCStressTest545=== RUN TestResurrectedObjectNotDeleted546=== PAUSE TestResurrectedObjectNotDeleted547=== RUN TestCreatePin_ReservedPins548=== PAUSE TestCreatePin_ReservedPins549=== RUN TestParseSingleRange550=== PAUSE TestParseSingleRange551=== RUN TestIsValidCachePath552=== PAUSE TestIsValidCachePath553=== RUN TestReadProxyNarinfo554=== PAUSE TestReadProxyNarinfo555=== RUN TestReadProxyNarinfoAlreadyDecompressed556=== PAUSE TestReadProxyNarinfoAlreadyDecompressed557=== RUN TestReadProxyNarStreaming558=== PAUSE TestReadProxyNarStreaming559=== RUN TestReadProxy404560=== PAUSE TestReadProxy404561=== RUN TestReadProxyInvalidPath562=== PAUSE TestReadProxyInvalidPath563=== RUN TestReadProxyHead564=== PAUSE TestReadProxyHead565=== RUN TestReadProxyConditionalGet566=== PAUSE TestReadProxyConditionalGet567=== RUN TestReadProxyRootRedirectsToIndexHTML568=== PAUSE TestReadProxyRootRedirectsToIndexHTML569=== RUN TestReadProxyDisabled570=== PAUSE TestReadProxyDisabled571=== RUN TestReadRedirectNar572=== PAUSE TestReadRedirectNar573=== RUN TestReadRedirectKeepsNarinfoProxied574=== PAUSE TestReadRedirectKeepsNarinfoProxied575=== RUN TestReadProxyRangeRequest576=== PAUSE TestReadProxyRangeRequest577=== RUN TestReadRedirectUsesPublicS3URL578=== PAUSE TestReadRedirectUsesPublicS3URL579=== RUN TestRedundantMultipartUpload580=== PAUSE TestRedundantMultipartUpload581=== RUN TestCompleteMultipartUpload_ErrorButObjectExists582=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists583=== RUN TestCompletedNarNotReofferedAcrossClosures584=== PAUSE TestCompletedNarNotReofferedAcrossClosures585=== RUN TestPresignedUploadRegisteredBeforeCommit586=== PAUSE TestPresignedUploadRegisteredBeforeCommit587=== RUN TestService_Rustfstest588=== PAUSE TestService_Rustfstest589=== RUN TestParseSize590=== PAUSE TestParseSize591=== RUN TestSkippedUploadsHandler592=== PAUSE TestSkippedUploadsHandler593=== RUN TestSystemdListenerNotActivated594--- PASS: TestSystemdListenerNotActivated (0.00s)595=== RUN TestWatchdogBeatsWhenHealthy596--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)597=== RUN TestWatchdogSkipsWhenUnhealthy5982026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"5992026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6002026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6012026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6022026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6032026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6042026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6052026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6062026/09/22 08:50:35 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"607--- PASS: TestWatchdogSkipsWhenUnhealthy (0.21s)608=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle609=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle610=== RUN TestProxyWriteTimeout611=== PAUSE TestProxyWriteTimeout612=== RUN TestIsValidUploadKey613=== PAUSE TestIsValidUploadKey614=== RUN TestUploadHandlersRejectInvalidKeys615=== PAUSE TestUploadHandlersRejectInvalidKeys616=== RUN TestUploadHandlersRejectOversizedBody617=== PAUSE TestUploadHandlersRejectOversizedBody618=== RUN TestService_cleanupPendingClosuresHandler619=== PAUSE TestService_cleanupPendingClosuresHandler620=== RUN TestService_createPendingClosureHandler621=== PAUSE TestService_createPendingClosureHandler622=== RUN TestService_verifyS3Integrity623=== PAUSE TestService_verifyS3Integrity624=== RUN TestCompleteMultipartUnregistered625=== PAUSE TestCompleteMultipartUnregistered626=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT627=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT628=== CONT TestService_AuthMiddleware629=== CONT TestServerTLSConfig630=== CONT TestReadProxyRangeRequest631=== RUN TestServerTLSConfig/no_client_CA632=== CONT TestGCBugBareHashReferences633=== PAUSE TestServerTLSConfig/no_client_CA634=== RUN TestServerTLSConfig/missing_CA_file635=== PAUSE TestServerTLSConfig/missing_CA_file636=== CONT TestClientErrorHandling637=== RUN TestClientErrorHandling/InvalidStorePath638=== RUN TestServerTLSConfig/not_a_PEM_file639=== PAUSE TestServerTLSConfig/not_a_PEM_file640=== CONT TestReadRedirectKeepsNarinfoProxied641=== PAUSE TestClientErrorHandling/InvalidStorePath642=== RUN TestClientErrorHandling/InvalidAuthToken643=== PAUSE TestClientErrorHandling/InvalidAuthToken644=== CONT TestReadProxyHead645=== CONT TestReadRedirectNar646=== CONT TestReadProxyDisabled647=== CONT TestReadProxyRootRedirectsToIndexHTML648=== CONT TestReadProxyConditionalGet649=== RUN TestClientErrorHandling/ServerNotAvailable650=== PAUSE TestClientErrorHandling/ServerNotAvailable651=== CONT TestReadProxyInvalidPath6522026-09-22 08:50:35.570 UTC [74094] ERROR: relation "goose_db_version" does not exist at character 366532026-09-22 08:50:35.570 UTC [74094] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6542026-09-22 08:50:35.570 UTC [74095] ERROR: relation "goose_db_version" does not exist at character 366552026-09-22 08:50:35.570 UTC [74095] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6562026-09-22 08:50:35.572 UTC [74096] ERROR: relation "goose_db_version" does not exist at character 366572026-09-22 08:50:35.572 UTC [74096] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6582026-09-22 08:50:35.573 UTC [74097] ERROR: relation "goose_db_version" does not exist at character 366592026-09-22 08:50:35.573 UTC [74097] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6602026-09-22 08:50:35.574 UTC [74098] ERROR: relation "goose_db_version" does not exist at character 366612026-09-22 08:50:35.574 UTC [74098] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6622026-09-22 08:50:35.576 UTC [74101] ERROR: relation "goose_db_version" does not exist at character 366632026-09-22 08:50:35.576 UTC [74101] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6642026-09-22 08:50:35.576 UTC [74100] ERROR: relation "goose_db_version" does not exist at character 366652026-09-22 08:50:35.576 UTC [74100] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6662026-09-22 08:50:35.577 UTC [74103] ERROR: relation "goose_db_version" does not exist at character 366672026-09-22 08:50:35.577 UTC [74103] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6682026-09-22 08:50:35.577 UTC [74104] ERROR: relation "goose_db_version" does not exist at character 366692026-09-22 08:50:35.577 UTC [74104] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6702026-09-22 08:50:35.578 UTC [74102] ERROR: relation "goose_db_version" does not exist at character 366712026-09-22 08:50:35.578 UTC [74102] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC6722026/09/22 08:50:35 OK 20241026095416_initial_model.sql (6.36ms)6732026/09/22 08:50:35 OK 20241026095416_initial_model.sql (6.49ms)6742026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (794.67µs)6752026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (922.83µs)6762026/09/22 08:50:35 OK 20241026095416_initial_model.sql (6.51ms)6772026/09/22 08:50:35 OK 20251218171726_add_pins.sql (2.34ms)6782026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (915.46µs)6792026/09/22 08:50:35 OK 20251218171726_add_pins.sql (2.75ms)6802026/09/22 08:50:35 OK 20241026095416_initial_model.sql (8.09ms)6812026/09/22 08:50:35 OK 20241026095416_initial_model.sql (8.37ms)6822026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (1ms)6832026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.62ms)6842026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.92ms)6852026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.97ms)6862026/09/22 08:50:35 OK 20241026095416_initial_model.sql (7.43ms)6872026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (744.88µs)6882026/09/22 08:50:35 OK 20241026095416_initial_model.sql (6.24ms)6892026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (1.08ms)6902026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.71ms)6912026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.79ms)6922026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (817.29µs)6932026/09/22 08:50:35 OK 20260905000000_add_claims.sql (2.14ms)6942026/09/22 08:50:35 OK 20241026095416_initial_model.sql (8.41ms)6952026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (2.08ms)6962026/09/22 08:50:35 OK 20251218171726_add_pins.sql (2.08ms)6972026/09/22 08:50:35 OK 20241026095416_initial_model.sql (7.77ms)6982026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.29ms)6992026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007002026/09/22 08:50:35 OK 20241026095416_initial_model.sql (7.91ms)7012026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.56ms)7022026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.28ms)7032026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007042026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (1.12ms)7052026/09/22 08:50:35 OK 20251218171726_add_pins.sql (2.1ms)7062026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.85ms)7072026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (1.23ms)7082026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.52ms)7092026/09/22 08:50:35 OK 20251210153512_drop_unused_gin_index.sql (977.5µs)7102026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.55ms)7112026/09/22 08:50:35 OK 20260905000000_add_claims.sql (2.41ms)7122026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.33ms)7132026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.2ms)7142026/09/22 08:50:35 OK 2_object_stats_trigger.sql (665.83µs)7152026/09/22 08:50:35 goose: up to current file version: 27162026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.94ms)7172026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.98ms)7182026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.66ms)7192026/09/22 08:50:35 OK 2_object_stats_trigger.sql (797.21µs)7202026/09/22 08:50:35 goose: up to current file version: 27212026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.38ms)7222026/09/22 08:50:35 OK 20251218171726_add_pins.sql (1.87ms)7232026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.6ms)7242026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.76ms)7252026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007262026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.31ms)7272026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.31ms)7282026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007292026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.65ms)7302026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.2ms)7312026/09/22 08:50:35 OK 20260628120000_add_object_size_and_stats.sql (1.33ms)7322026/09/22 08:50:35 OK 1_commit_pending_closure.sql (895µs)7332026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.29ms)7342026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007352026/09/22 08:50:35 OK 20260905000000_add_claims.sql (2.39ms)7362026/09/22 08:50:35 OK 2_object_stats_trigger.sql (640.38µs)7372026/09/22 08:50:35 goose: up to current file version: 27382026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (1.41ms)7392026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007402026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.56ms)7412026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.57ms)7422026/09/22 08:50:35 OK 20260905000000_add_claims.sql (1.31ms)7432026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (923.71µs)7442026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007452026/09/22 08:50:35 OK 2_object_stats_trigger.sql (524.63µs)7462026/09/22 08:50:35 goose: up to current file version: 27472026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.55ms)7482026/09/22 08:50:35 OK 20260905000000_add_claims.sql (2.11ms)7492026/09/22 08:50:35 OK 1_commit_pending_closure.sql (884.92µs)7502026/09/22 08:50:35 OK 2_object_stats_trigger.sql (250.17µs)7512026/09/22 08:50:35 goose: up to current file version: 27522026/09/22 08:50:35 OK 2_object_stats_trigger.sql (202.08µs)7532026/09/22 08:50:35 goose: up to current file version: 27542026/09/22 08:50:35 OK 1_commit_pending_closure.sql (750.58µs)7552026/09/22 08:50:35 OK 2_object_stats_trigger.sql (214.58µs)7562026/09/22 08:50:35 goose: up to current file version: 27572026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (3.55ms)7582026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007592026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (3.37ms)7602026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007612026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.2ms)7622026/09/22 08:50:35 OK 1_commit_pending_closure.sql (1.03ms)7632026/09/22 08:50:35 OK 20260920000000_drop_claims.sql (4.01ms)7642026/09/22 08:50:35 goose: successfully migrated database to version: 202609200000007652026/09/22 08:50:35 OK 2_object_stats_trigger.sql (188.29µs)7662026/09/22 08:50:35 goose: up to current file version: 27672026/09/22 08:50:35 OK 2_object_stats_trigger.sql (183.71µs)7682026/09/22 08:50:35 goose: up to current file version: 27692026/09/22 08:50:35 OK 1_commit_pending_closure.sql (656.67µs)7702026/09/22 08:50:35 OK 2_object_stats_trigger.sql (188µs)7712026/09/22 08:50:35 goose: up to current file version: 2772--- PASS: TestReadRedirectKeepsNarinfoProxied (0.43s)773=== CONT TestReadProxy4047742026/09/22 08:50:35 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"775--- PASS: TestService_AuthMiddleware (0.54s)776=== CONT TestReadProxyNarStreaming777--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.70s)778=== CONT TestReadProxyNarinfoAlreadyDecompressed779--- PASS: TestReadProxyRangeRequest (0.89s)780=== CONT TestReadProxyNarinfo781--- PASS: TestReadProxyHead (1.05s)782=== CONT TestIsValidCachePath783=== RUN TestIsValidCachePath/narinfo784=== PAUSE TestIsValidCachePath/narinfo785=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars786=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars787=== RUN TestIsValidCachePath/nar_zst788=== PAUSE TestIsValidCachePath/nar_zst789=== RUN TestIsValidCachePath/nar_xz790=== PAUSE TestIsValidCachePath/nar_xz791=== RUN TestIsValidCachePath/nar_bz2792=== PAUSE TestIsValidCachePath/nar_bz2793=== RUN TestIsValidCachePath/nar_uncompressed794=== PAUSE TestIsValidCachePath/nar_uncompressed795=== RUN TestIsValidCachePath/ls796=== PAUSE TestIsValidCachePath/ls797=== RUN TestIsValidCachePath/log798=== PAUSE TestIsValidCachePath/log799=== RUN TestIsValidCachePath/realisation800=== PAUSE TestIsValidCachePath/realisation801=== RUN TestIsValidCachePath/nix-cache-info802=== PAUSE TestIsValidCachePath/nix-cache-info803=== RUN TestIsValidCachePath/index.html804=== PAUSE TestIsValidCachePath/index.html805=== RUN TestIsValidCachePath/traversal_parent806=== PAUSE TestIsValidCachePath/traversal_parent807=== RUN TestIsValidCachePath/traversal_in_middle808=== PAUSE TestIsValidCachePath/traversal_in_middle809=== RUN TestIsValidCachePath/invalid_char_e810=== PAUSE TestIsValidCachePath/invalid_char_e811=== RUN TestIsValidCachePath/invalid_char_u812=== PAUSE TestIsValidCachePath/invalid_char_u813=== RUN TestIsValidCachePath/random_path814=== PAUSE TestIsValidCachePath/random_path815=== RUN TestIsValidCachePath/empty816=== PAUSE TestIsValidCachePath/empty817=== RUN TestIsValidCachePath/leading_slash818=== PAUSE TestIsValidCachePath/leading_slash819=== RUN TestIsValidCachePath/wrong_extension820=== PAUSE TestIsValidCachePath/wrong_extension821=== RUN TestIsValidCachePath/short_hash822=== PAUSE TestIsValidCachePath/short_hash823=== CONT TestParseSingleRange824=== RUN TestParseSingleRange/none825=== PAUSE TestParseSingleRange/none826=== RUN TestParseSingleRange/unknown_unit827=== PAUSE TestParseSingleRange/unknown_unit828=== RUN TestParseSingleRange/multi-range_ignored829=== PAUSE TestParseSingleRange/multi-range_ignored830=== RUN TestParseSingleRange/malformed_no_dash831=== PAUSE TestParseSingleRange/malformed_no_dash832=== RUN TestParseSingleRange/malformed_both_empty833=== PAUSE TestParseSingleRange/malformed_both_empty834=== RUN TestParseSingleRange/malformed_end_before_start835=== PAUSE TestParseSingleRange/malformed_end_before_start836=== RUN TestParseSingleRange/closed837=== PAUSE TestParseSingleRange/closed838=== RUN TestParseSingleRange/open-ended839=== PAUSE TestParseSingleRange/open-ended840=== RUN TestParseSingleRange/end_clamped_to_size841=== PAUSE TestParseSingleRange/end_clamped_to_size842=== RUN TestParseSingleRange/suffix843=== PAUSE TestParseSingleRange/suffix844=== RUN TestParseSingleRange/suffix_exceeds_size845=== PAUSE TestParseSingleRange/suffix_exceeds_size846=== RUN TestParseSingleRange/single_byte847=== PAUSE TestParseSingleRange/single_byte848=== RUN TestParseSingleRange/start_past_EOF849=== PAUSE TestParseSingleRange/start_past_EOF850=== RUN TestParseSingleRange/start_far_past_EOF851=== PAUSE TestParseSingleRange/start_far_past_EOF852=== CONT TestCreatePin_ReservedPins8532026-09-22 08:50:36.408 UTC [74115] ERROR: relation "goose_db_version" does not exist at character 368542026-09-22 08:50:36.408 UTC [74115] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8552026/09/22 08:50:36 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53071/oidc856--- PASS: TestReadRedirectNar (1.21s)857=== CONT TestResurrectedObjectNotDeleted8582026-09-22 08:50:36.501 UTC [74118] ERROR: relation "goose_db_version" does not exist at character 368592026-09-22 08:50:36.501 UTC [74118] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8602026/09/22 08:50:36 OK 20241026095416_initial_model.sql (78.85ms)8612026/09/22 08:50:36 OK 20251210153512_drop_unused_gin_index.sql (4.17ms)8622026/09/22 08:50:36 OK 20251218171726_add_pins.sql (21.07ms)8632026/09/22 08:50:36 OK 20260628120000_add_object_size_and_stats.sql (31.57ms)8642026/09/22 08:50:36 OK 20241026095416_initial_model.sql (56.61ms)8652026/09/22 08:50:36 OK 20251210153512_drop_unused_gin_index.sql (5.89ms)8662026/09/22 08:50:36 OK 20260905000000_add_claims.sql (23.41ms)8672026/09/22 08:50:36 OK 20251218171726_add_pins.sql (8.34ms)8682026/09/22 08:50:36 OK 20260920000000_drop_claims.sql (20.1ms)8692026/09/22 08:50:36 goose: successfully migrated database to version: 202609200000008702026-09-22 08:50:36.611 UTC [74121] ERROR: relation "goose_db_version" does not exist at character 368712026-09-22 08:50:36.611 UTC [74121] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8722026/09/22 08:50:36 OK 20260628120000_add_object_size_and_stats.sql (19.52ms)8732026/09/22 08:50:36 OK 1_commit_pending_closure.sql (1.82ms)8742026/09/22 08:50:36 OK 2_object_stats_trigger.sql (312.38µs)8752026/09/22 08:50:36 goose: up to current file version: 28762026/09/22 08:50:36 OK 20260905000000_add_claims.sql (20.85ms)877--- PASS: TestReadProxyInvalidPath (1.37s)878=== CONT TestOrphanedObjectsGCStressTest8792026/09/22 08:50:36 OK 20260920000000_drop_claims.sql (22.79ms)8802026/09/22 08:50:36 goose: successfully migrated database to version: 202609200000008812026/09/22 08:50:36 OK 1_commit_pending_closure.sql (1.35ms)8822026/09/22 08:50:36 OK 2_object_stats_trigger.sql (339.5µs)8832026/09/22 08:50:36 goose: up to current file version: 28842026/09/22 08:50:36 OK 20241026095416_initial_model.sql (63.44ms)8852026/09/22 08:50:36 OK 20251210153512_drop_unused_gin_index.sql (1.03ms)8862026/09/22 08:50:36 OK 20251218171726_add_pins.sql (12.22ms)8872026/09/22 08:50:36 OK 20260628120000_add_object_size_and_stats.sql (19.14ms)8882026-09-22 08:50:36.756 UTC [74127] ERROR: relation "goose_db_version" does not exist at character 368892026-09-22 08:50:36.756 UTC [74127] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8902026/09/22 08:50:36 OK 20260905000000_add_claims.sql (28.01ms)8912026/09/22 08:50:36 OK 20260920000000_drop_claims.sql (21.28ms)8922026/09/22 08:50:36 goose: successfully migrated database to version: 202609200000008932026/09/22 08:50:36 OK 1_commit_pending_closure.sql (4.29ms)8942026/09/22 08:50:36 OK 2_object_stats_trigger.sql (810.67µs)8952026/09/22 08:50:36 goose: up to current file version: 28962026/09/22 08:50:36 OK 20241026095416_initial_model.sql (64.84ms)8972026/09/22 08:50:36 OK 20251210153512_drop_unused_gin_index.sql (7.91ms)8982026/09/22 08:50:36 OK 20251218171726_add_pins.sql (18.44ms)8992026/09/22 08:50:36 OK 20260628120000_add_object_size_and_stats.sql (21.12ms)9002026/09/22 08:50:36 OK 20260905000000_add_claims.sql (23.48ms)9012026/09/22 08:50:36 OK 20260920000000_drop_claims.sql (18.44ms)9022026/09/22 08:50:36 goose: successfully migrated database to version: 202609200000009032026/09/22 08:50:36 OK 1_commit_pending_closure.sql (5.46ms)9042026/09/22 08:50:36 OK 2_object_stats_trigger.sql (1.42ms)9052026/09/22 08:50:36 goose: up to current file version: 2906--- PASS: TestReadProxyConditionalGet (1.70s)907=== CONT TestOrphanedObjectsGC908--- PASS: TestGCBugBareHashReferences (1.77s)909=== CONT TestObjectStatsTrigger910--- PASS: TestReadProxyDisabled (1.84s)911=== CONT TestMultipartCleanup912--- PASS: TestReadProxy404 (1.56s)913=== CONT TestProxyWriteTimeout914=== RUN TestProxyWriteTimeout/narinfo915=== PAUSE TestProxyWriteTimeout/narinfo916=== RUN TestProxyWriteTimeout/1_GiB_nar917=== PAUSE TestProxyWriteTimeout/1_GiB_nar918=== RUN TestProxyWriteTimeout/10_GiB_nar919=== PAUSE TestProxyWriteTimeout/10_GiB_nar920=== RUN TestProxyWriteTimeout/unknown_size921=== PAUSE TestProxyWriteTimeout/unknown_size922=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT9232026-09-22 08:50:37.359 UTC [74137] ERROR: relation "goose_db_version" does not exist at character 369242026-09-22 08:50:37.359 UTC [74137] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9252026-09-22 08:50:37.373 UTC [74136] ERROR: relation "goose_db_version" does not exist at character 369262026-09-22 08:50:37.373 UTC [74136] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC927--- PASS: TestReadProxyNarStreaming (1.67s)928=== CONT TestCompleteMultipartUnregistered9292026/09/22 08:50:37 OK 20241026095416_initial_model.sql (143.17ms)9302026/09/22 08:50:37 OK 20251210153512_drop_unused_gin_index.sql (12.13ms)9312026/09/22 08:50:37 OK 20241026095416_initial_model.sql (137.11ms)9322026/09/22 08:50:37 OK 20251210153512_drop_unused_gin_index.sql (10.18ms)9332026/09/22 08:50:37 OK 20251218171726_add_pins.sql (12.37ms)9342026/09/22 08:50:37 OK 20251218171726_add_pins.sql (13.83ms)9352026/09/22 08:50:37 OK 20260628120000_add_object_size_and_stats.sql (23.63ms)9362026/09/22 08:50:37 OK 20260628120000_add_object_size_and_stats.sql (18.35ms)9372026-09-22 08:50:37.597 UTC [74140] ERROR: relation "goose_db_version" does not exist at character 369382026-09-22 08:50:37.597 UTC [74140] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9392026/09/22 08:50:37 OK 20260905000000_add_claims.sql (26.87ms)9402026/09/22 08:50:37 OK 20260905000000_add_claims.sql (41.27ms)9412026/09/22 08:50:37 OK 20260920000000_drop_claims.sql (14.66ms)9422026/09/22 08:50:37 goose: successfully migrated database to version: 202609200000009432026/09/22 08:50:37 OK 1_commit_pending_closure.sql (3.91ms)9442026/09/22 08:50:37 OK 2_object_stats_trigger.sql (716.17µs)9452026/09/22 08:50:37 goose: up to current file version: 29462026/09/22 08:50:37 OK 20260920000000_drop_claims.sql (24.42ms)9472026/09/22 08:50:37 goose: successfully migrated database to version: 202609200000009482026/09/22 08:50:37 OK 1_commit_pending_closure.sql (3.26ms)9492026/09/22 08:50:37 OK 2_object_stats_trigger.sql (1.49ms)9502026/09/22 08:50:37 goose: up to current file version: 2951--- PASS: TestReadProxyNarinfoAlreadyDecompressed (1.72s)952=== CONT TestService_verifyS3Integrity9532026/09/22 08:50:37 OK 20241026095416_initial_model.sql (101.89ms)9542026/09/22 08:50:37 OK 20251210153512_drop_unused_gin_index.sql (10.82ms)9552026/09/22 08:50:37 OK 20251218171726_add_pins.sql (16.42ms)9562026/09/22 08:50:37 OK 20260628120000_add_object_size_and_stats.sql (32.07ms)9572026/09/22 08:50:37 OK 20260905000000_add_claims.sql (41.73ms)958--- PASS: TestReadProxyNarinfo (1.73s)959=== CONT TestService_createPendingClosureHandler9602026/09/22 08:50:37 OK 20260920000000_drop_claims.sql (34.35ms)9612026/09/22 08:50:37 goose: successfully migrated database to version: 202609200000009622026/09/22 08:50:37 OK 1_commit_pending_closure.sql (47.58ms)9632026/09/22 08:50:37 OK 2_object_stats_trigger.sql (1.02ms)9642026/09/22 08:50:37 goose: up to current file version: 29652026-09-22 08:50:38.025 UTC [74148] ERROR: relation "goose_db_version" does not exist at character 369662026-09-22 08:50:38.025 UTC [74148] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9672026/09/22 08:50:38 OK 20241026095416_initial_model.sql (136.63ms)9682026/09/22 08:50:38 OK 20251210153512_drop_unused_gin_index.sql (14.98ms)9692026-09-22 08:50:38.230 UTC [74149] ERROR: relation "goose_db_version" does not exist at character 369702026-09-22 08:50:38.230 UTC [74149] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC971--- PASS: TestResurrectedObjectNotDeleted (1.76s)972=== CONT TestService_cleanupPendingClosuresHandler9732026/09/22 08:50:38 OK 20251218171726_add_pins.sql (34.71ms)9742026/09/22 08:50:38 OK 20260628120000_add_object_size_and_stats.sql (32.77ms)9752026-09-22 08:50:38.325 UTC [74152] ERROR: relation "goose_db_version" does not exist at character 369762026-09-22 08:50:38.325 UTC [74152] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9772026/09/22 08:50:38 OK 20260905000000_add_claims.sql (35.39ms)9782026/09/22 08:50:38 OK 20260920000000_drop_claims.sql (15.75ms)9792026/09/22 08:50:38 goose: successfully migrated database to version: 202609200000009802026/09/22 08:50:38 OK 1_commit_pending_closure.sql (2.76ms)9812026/09/22 08:50:38 OK 2_object_stats_trigger.sql (578.42µs)9822026/09/22 08:50:38 goose: up to current file version: 29832026/09/22 08:50:38 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9842026/09/22 08:50:38 WARN Refused reserved pin name=worker-x86_64-linux9852026/09/22 08:50:38 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9862026/09/22 08:50:38 INFO Received create pin request method=POST path=/api/pins/my-app9872026/09/22 08:50:38 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux988--- PASS: TestCreatePin_ReservedPins (2.07s)989=== CONT TestUploadHandlersRejectOversizedBody9902026/09/22 08:50:38 OK 20241026095416_initial_model.sql (107.86ms)9912026/09/22 08:50:38 OK 20251210153512_drop_unused_gin_index.sql (5.21ms)992=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure993=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure994=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart995=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart996=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts997=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts998=== CONT TestUploadHandlersRejectInvalidKeys999=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1000=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1001=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1002=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1003=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1004=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1005=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1006=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1007=== CONT TestIsValidUploadKey1008=== RUN TestIsValidUploadKey/narinfo1009=== PAUSE TestIsValidUploadKey/narinfo1010=== RUN TestIsValidUploadKey/nar_zst1011=== PAUSE TestIsValidUploadKey/nar_zst1012=== RUN TestIsValidUploadKey/nar_xz1013=== PAUSE TestIsValidUploadKey/nar_xz1014=== RUN TestIsValidUploadKey/nar_plain1015=== PAUSE TestIsValidUploadKey/nar_plain1016=== RUN TestIsValidUploadKey/listing1017=== PAUSE TestIsValidUploadKey/listing1018=== RUN TestIsValidUploadKey/build_log1019=== PAUSE TestIsValidUploadKey/build_log1020=== RUN TestIsValidUploadKey/build_log_home-manager_file1021=== PAUSE TestIsValidUploadKey/build_log_home-manager_file1022=== RUN TestIsValidUploadKey/build_log_plus_in_name1023=== PAUSE TestIsValidUploadKey/build_log_plus_in_name1024=== RUN TestIsValidUploadKey/build_log_question_mark1025=== PAUSE TestIsValidUploadKey/build_log_question_mark1026=== RUN TestIsValidUploadKey/build_log_equals1027=== PAUSE TestIsValidUploadKey/build_log_equals1028=== RUN TestIsValidUploadKey/realisation1029=== PAUSE TestIsValidUploadKey/realisation1030=== RUN TestIsValidUploadKey/realisation_plus_in_output1031=== PAUSE TestIsValidUploadKey/realisation_plus_in_output1032=== RUN TestIsValidUploadKey/nix-cache-info1033=== PAUSE TestIsValidUploadKey/nix-cache-info1034=== RUN TestIsValidUploadKey/index.html1035=== PAUSE TestIsValidUploadKey/index.html1036=== RUN TestIsValidUploadKey/narinfo_key,_nar_type1037=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type1038=== RUN TestIsValidUploadKey/nar_key,_narinfo_type1039=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type1040=== RUN TestIsValidUploadKey/listing_key,_narinfo_type1041=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type1042=== RUN TestIsValidUploadKey/traversal1043=== PAUSE TestIsValidUploadKey/traversal1044=== RUN TestIsValidUploadKey/traversal_nar1045=== PAUSE TestIsValidUploadKey/traversal_nar1046=== RUN TestIsValidUploadKey/absolute1047=== PAUSE TestIsValidUploadKey/absolute1048=== RUN TestIsValidUploadKey/empty_key1049=== PAUSE TestIsValidUploadKey/empty_key1050=== RUN TestIsValidUploadKey/unknown_type1051=== PAUSE TestIsValidUploadKey/unknown_type1052=== CONT TestPinProtectsFromGC10532026/09/22 08:50:38 OK 20251218171726_add_pins.sql (7.56ms)10542026/09/22 08:50:38 OK 20260628120000_add_object_size_and_stats.sql (13.13ms)10552026/09/22 08:50:38 OK 20241026095416_initial_model.sql (80.23ms)10562026/09/22 08:50:38 OK 20251210153512_drop_unused_gin_index.sql (1.31ms)10572026-09-22 08:50:38.447 UTC [74155] ERROR: relation "goose_db_version" does not exist at character 3610582026-09-22 08:50:38.447 UTC [74155] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10592026/09/22 08:50:38 OK 20260905000000_add_claims.sql (15.94ms)10602026/09/22 08:50:38 OK 20260920000000_drop_claims.sql (15.35ms)10612026/09/22 08:50:38 goose: successfully migrated database to version: 2026092000000010622026/09/22 08:50:38 OK 20251218171726_add_pins.sql (15.88ms)10632026/09/22 08:50:38 OK 1_commit_pending_closure.sql (1.86ms)10642026/09/22 08:50:38 OK 2_object_stats_trigger.sql (389.5µs)10652026/09/22 08:50:38 goose: up to current file version: 210662026/09/22 08:50:38 OK 20260628120000_add_object_size_and_stats.sql (13.23ms)10672026/09/22 08:50:38 OK 20260905000000_add_claims.sql (26.41ms)10682026/09/22 08:50:38 OK 20260920000000_drop_claims.sql (15.54ms)10692026/09/22 08:50:38 goose: successfully migrated database to version: 2026092000000010702026/09/22 08:50:38 OK 1_commit_pending_closure.sql (1.99ms)10712026/09/22 08:50:38 OK 2_object_stats_trigger.sql (408.5µs)10722026/09/22 08:50:38 goose: up to current file version: 210732026/09/22 08:50:38 OK 20241026095416_initial_model.sql (94.15ms)10742026/09/22 08:50:38 OK 20251210153512_drop_unused_gin_index.sql (10.78ms)10752026/09/22 08:50:38 OK 20251218171726_add_pins.sql (9.48ms)10762026/09/22 08:50:38 OK 20260628120000_add_object_size_and_stats.sql (43.06ms)10772026/09/22 08:50:38 OK 20260905000000_add_claims.sql (49.04ms)10782026/09/22 08:50:38 OK 20260920000000_drop_claims.sql (22.39ms)10792026/09/22 08:50:38 goose: successfully migrated database to version: 2026092000000010802026/09/22 08:50:38 OK 1_commit_pending_closure.sql (5.5ms)10812026/09/22 08:50:38 OK 2_object_stats_trigger.sql (916.33µs)10822026/09/22 08:50:38 goose: up to current file version: 210832026-09-22 08:50:38.763 UTC [74159] ERROR: relation "goose_db_version" does not exist at character 3610842026-09-22 08:50:38.763 UTC [74159] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10852026/09/22 08:50:38 OK 20241026095416_initial_model.sql (161.67ms)10862026-09-22 08:50:38.979 UTC [74160] ERROR: relation "goose_db_version" does not exist at character 3610872026-09-22 08:50:38.979 UTC [74160] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10882026/09/22 08:50:38 OK 20251210153512_drop_unused_gin_index.sql (5.42ms)10892026/09/22 08:50:39 OK 20251218171726_add_pins.sql (30.25ms)10902026/09/22 08:50:39 OK 20260628120000_add_object_size_and_stats.sql (32.5ms)1091--- PASS: TestObjectStatsTrigger (2.03s)1092=== CONT TestLeadEndsOnShutdown10932026/09/22 08:50:39 OK 20260905000000_add_claims.sql (46.86ms)10942026/09/22 08:50:39 OK 20260920000000_drop_claims.sql (31.03ms)10952026/09/22 08:50:39 goose: successfully migrated database to version: 2026092000000010962026/09/22 08:50:39 OK 1_commit_pending_closure.sql (3.01ms)10972026/09/22 08:50:39 OK 2_object_stats_trigger.sql (557.17µs)10982026/09/22 08:50:39 goose: up to current file version: 210992026/09/22 08:50:39 OK 20241026095416_initial_model.sql (167.07ms)11002026/09/22 08:50:39 OK 20251210153512_drop_unused_gin_index.sql (12.73ms)11012026/09/22 08:50:39 OK 20251218171726_add_pins.sql (26.05ms)11022026/09/22 08:50:39 INFO Received uploads request method=POST path=/api/pending_closures11032026/09/22 08:50:39 OK 20260628120000_add_object_size_and_stats.sql (29.46ms)11042026/09/22 08:50:39 OK 20260905000000_add_claims.sql (59.31ms)11052026/09/22 08:50:39 OK 20260920000000_drop_claims.sql (14.73ms)11062026/09/22 08:50:39 goose: successfully migrated database to version: 2026092000000011072026-09-22 08:50:39.345 UTC [74163] ERROR: relation "goose_db_version" does not exist at character 3611082026-09-22 08:50:39.345 UTC [74163] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11092026/09/22 08:50:39 OK 1_commit_pending_closure.sql (6.66ms)11102026/09/22 08:50:39 OK 2_object_stats_trigger.sql (1.06ms)11112026/09/22 08:50:39 goose: up to current file version: 211122026/09/22 08:50:39 INFO Received cleanup request method=DELETE path=/api/pending_closures11132026/09/22 08:50:39 INFO Aborted multipart uploads count=11114=== NAME TestOrphanedObjectsGC1115 orphaned_objects_gc_test.go:290: GC Test Summary:1116 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1117 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1118 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1119 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1120 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1121--- PASS: TestOrphanedObjectsGC (2.51s)1122=== CONT TestLeadElectsOneAndHandsOver1123--- PASS: TestMultipartCleanup (2.38s)1124=== CONT TestResolveDBConnectionString1125=== RUN TestResolveDBConnectionString/flag_wins1126=== PAUSE TestResolveDBConnectionString/flag_wins1127=== RUN TestResolveDBConnectionString/file_when_flag_empty1128=== PAUSE TestResolveDBConnectionString/file_when_flag_empty1129=== RUN TestResolveDBConnectionString/missing_file_is_an_error1130=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error1131=== RUN TestResolveDBConnectionString/PGHOST_allows_empty1132=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty1133=== RUN TestResolveDBConnectionString/nothing_configured1134=== PAUSE TestResolveDBConnectionString/nothing_configured1135=== CONT TestClientReportsSignatures11362026/09/22 08:50:39 INFO Received uploads request method=POST path=/api/pending_closures11372026/09/22 08:50:39 OK 20241026095416_initial_model.sql (186.28ms)11382026/09/22 08:50:39 OK 20251210153512_drop_unused_gin_index.sql (13.43ms)1139--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (2.39s)1140=== CONT TestPresignedUploadRegisteredBeforeCommit11412026/09/22 08:50:39 OK 20251218171726_add_pins.sql (30.48ms)11422026/09/22 08:50:39 OK 20260628120000_add_object_size_and_stats.sql (32.96ms)11432026/09/22 08:50:39 OK 20260905000000_add_claims.sql (45.3ms)11442026/09/22 08:50:39 OK 20260920000000_drop_claims.sql (25.25ms)11452026/09/22 08:50:39 goose: successfully migrated database to version: 2026092000000011462026/09/22 08:50:39 OK 1_commit_pending_closure.sql (3.19ms)11472026/09/22 08:50:39 OK 2_object_stats_trigger.sql (654.38µs)11482026/09/22 08:50:39 goose: up to current file version: 211492026-09-22 08:50:39.825 UTC [74174] ERROR: relation "goose_db_version" does not exist at character 3611502026-09-22 08:50:39.825 UTC [74174] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11512026/09/22 08:50:39 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11522026/09/22 08:50:39 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1153--- PASS: TestCompleteMultipartUnregistered (2.39s)1154=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle11552026-09-22 08:50:39.910 UTC [74176] ERROR: relation "goose_db_version" does not exist at character 3611562026-09-22 08:50:39.910 UTC [74176] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11572026/09/22 08:50:40 OK 20241026095416_initial_model.sql (155.41ms)11582026/09/22 08:50:40 OK 20251210153512_drop_unused_gin_index.sql (9.45ms)11592026/09/22 08:50:40 OK 20251218171726_add_pins.sql (24.94ms)11602026/09/22 08:50:40 OK 20260628120000_add_object_size_and_stats.sql (39.43ms)11612026/09/22 08:50:40 OK 20241026095416_initial_model.sql (144.27ms)11622026/09/22 08:50:40 OK 20251210153512_drop_unused_gin_index.sql (8.92ms)11632026/09/22 08:50:40 INFO Received uploads request method=POST path=/api/pending_closures11642026/09/22 08:50:40 OK 20251218171726_add_pins.sql (93.6ms)11652026/09/22 08:50:40 OK 20260905000000_add_claims.sql (117.18ms)11662026/09/22 08:50:40 OK 20260628120000_add_object_size_and_stats.sql (48.6ms)11672026/09/22 08:50:40 OK 20260920000000_drop_claims.sql (59.13ms)11682026/09/22 08:50:40 goose: successfully migrated database to version: 2026092000000011692026/09/22 08:50:40 OK 1_commit_pending_closure.sql (5.1ms)11702026/09/22 08:50:40 OK 2_object_stats_trigger.sql (1.25ms)11712026/09/22 08:50:40 goose: up to current file version: 211722026/09/22 08:50:40 OK 20260905000000_add_claims.sql (30.15ms)11732026/09/22 08:50:40 OK 20260920000000_drop_claims.sql (36.25ms)11742026/09/22 08:50:40 goose: successfully migrated database to version: 2026092000000011752026/09/22 08:50:40 OK 1_commit_pending_closure.sql (3.08ms)11762026/09/22 08:50:40 OK 2_object_stats_trigger.sql (628.17µs)11772026/09/22 08:50:40 goose: up to current file version: 211782026/09/22 08:50:40 INFO Received uploads request method=POST path=/api/pending_closures11792026/09/22 08:50:40 INFO Received uploads request method=POST path=/api/pending_closures11802026/09/22 08:50:40 INFO Received uploads request method=POST path=/api/pending_closures11812026-09-22 08:50:40.796 UTC [74178] ERROR: relation "goose_db_version" does not exist at character 3611822026-09-22 08:50:40.796 UTC [74178] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11832026/09/22 08:50:40 INFO Received cleanup request method=DELETE path=/api/pending_closures11842026/09/22 08:50:40 INFO Aborted multipart uploads count=011852026/09/22 08:50:40 INFO Received uploads request method=POST path=/api/pending_closures11862026/09/22 08:50:40 INFO Received cleanup request method=DELETE path=/api/pending_closures11872026/09/22 08:50:40 INFO Aborted multipart uploads count=111882026/09/22 08:50:40 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete11892026-09-22 08:50:40.924 UTC [74174] ERROR: Closure does not exist: id=111902026-09-22 08:50:40.924 UTC [74174] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE11912026-09-22 08:50:40.924 UTC [74174] STATEMENT: -- name: CommitPendingClosure :exec1192 SELECT commit_pending_closure($1::bigint)1193 1194--- PASS: TestService_cleanupPendingClosuresHandler (2.68s)1195=== CONT TestSkippedUploadsHandler11962026/09/22 08:50:40 INFO Client skipped oversized paths paths=3 nar_bytes=50000000001197--- PASS: TestSkippedUploadsHandler (0.00s)1198=== CONT TestParseSize1199--- PASS: TestParseSize (0.00s)1200=== CONT TestService_Rustfstest12012026/09/22 08:50:41 OK 20241026095416_initial_model.sql (239.38ms)12022026/09/22 08:50:41 OK 20251210153512_drop_unused_gin_index.sql (13.89ms)12032026/09/22 08:50:41 OK 20251218171726_add_pins.sql (27.56ms)12042026/09/22 08:50:41 OK 20260628120000_add_object_size_and_stats.sql (50.28ms)12052026/09/22 08:50:41 OK 20260905000000_add_claims.sql (68.25ms)12062026/09/22 08:50:41 OK 20260920000000_drop_claims.sql (49.63ms)12072026/09/22 08:50:41 goose: successfully migrated database to version: 2026092000000012082026/09/22 08:50:41 OK 1_commit_pending_closure.sql (1.42ms)12092026/09/22 08:50:41 OK 2_object_stats_trigger.sql (295.17µs)12102026/09/22 08:50:41 goose: up to current file version: 21211=== NAME TestPinProtectsFromGC1212 client_integration_test.go:732: Pinned store path: /nix/var/nix/builds/nix-73934-291241723/TestPinProtectsFromGC2416160635/001/store/n4fkr8qph5bxr6nd33xc80319s5nmfqb-pinned-file.txt1213 client_integration_test.go:733: Unpinned store path: /nix/var/nix/builds/nix-73934-291241723/TestPinProtectsFromGC2416160635/001/store/xqzcswvlwj4kfr06n4n9b6f0d7zgyww8-unpinned-file.txt12142026/09/22 08:50:41 INFO lead: acquired remote=192.0.2.1:123412152026/09/22 08:50:41 INFO lead: released remote=192.0.2.1:12341216--- PASS: TestLeadEndsOnShutdown (2.64s)1217=== CONT TestClientWithDependencies12182026-09-22 08:50:41.724 UTC [74189] ERROR: relation "goose_db_version" does not exist at character 3612192026-09-22 08:50:41.724 UTC [74189] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12202026-09-22 08:50:41.785 UTC [74193] ERROR: relation "goose_db_version" does not exist at character 3612212026-09-22 08:50:41.785 UTC [74193] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12222026/09/22 08:50:41 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"12232026/09/22 08:50:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete12242026/09/22 08:50:41 INFO Received uploads request method=POST path=/api/pending_closures12252026/09/22 08:50:41 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLmQwM2U2YTFhLTlhNWUtNDhkOC1iOWUzLTFkOGYwMzMzNmFjYXgxNzkwMDY3MDQwMjQzODgyMDAw parts=1012262026/09/22 08:50:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete12272026/09/22 08:50:41 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12282026/09/22 08:50:41 INFO Uploading n4fkr8qph5bxr6nd33xc80319s5nmfqb-pinned-file.txt (128B)12292026/09/22 08:50:41 INFO Completed upload id=112302026/09/22 08:50:41 INFO Received uploads request method=POST path=/api/pending_closures12312026/09/22 08:50:41 INFO Received uploads request method=POST path=/api/pending_closures12322026/09/22 08:50:41 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo12332026/09/22 08:50:41 WARN Found objects in DB but missing from S3, will re-upload count=11234--- PASS: TestService_verifyS3Integrity (4.26s)1235=== CONT TestClientSharedPathCommittedMidPush12362026/09/22 08:50:41 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"12372026/09/22 08:50:41 WARN Failed to register uploaded object key=n4fkr8qph5bxr6nd33xc80319s5nmfqb.ls error="server returned 404: 404 page not found\n"12382026/09/22 08:50:41 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign12392026/09/22 08:50:41 INFO Signed narinfos id=1 count=112402026/09/22 08:50:41 INFO Uploading 1 narinfos12412026/09/22 08:50:42 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete12422026/09/22 08:50:42 WARN Failed to register uploaded object key=n4fkr8qph5bxr6nd33xc80319s5nmfqb.narinfo error="server returned 404: 404 page not found\n"12432026/09/22 08:50:42 OK 20241026095416_initial_model.sql (227.15ms)12442026-09-22 08:50:42.021 UTC [74203] ERROR: relation "goose_db_version" does not exist at character 3612452026-09-22 08:50:42.021 UTC [74203] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12462026/09/22 08:50:42 OK 20251210153512_drop_unused_gin_index.sql (9.45ms)12472026/09/22 08:50:42 OK 20241026095416_initial_model.sql (152.46ms)12482026/09/22 08:50:42 INFO Completed upload id=112492026/09/22 08:50:42 INFO Upload complete. (294ms)12502026/09/22 08:50:42 OK 20251210153512_drop_unused_gin_index.sql (15.42ms)12512026/09/22 08:50:42 OK 20251218171726_add_pins.sql (68.45ms)12522026/09/22 08:50:42 OK 20251218171726_add_pins.sql (89.71ms)12532026/09/22 08:50:42 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"12542026/09/22 08:50:42 INFO Received complete multipart upload request method=POST path=/api/multipart/complete12552026/09/22 08:50:42 OK 20260628120000_add_object_size_and_stats.sql (25.1ms)12562026/09/22 08:50:42 OK 20260628120000_add_object_size_and_stats.sql (39.11ms)12572026/09/22 08:50:42 INFO Received uploads request method=POST path=/api/pending_closures12582026/09/22 08:50:42 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12592026/09/22 08:50:42 INFO Uploading xqzcswvlwj4kfr06n4n9b6f0d7zgyww8-unpinned-file.txt (128B)12602026/09/22 08:50:42 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"12612026-09-22 08:50:42.195 UTC [74210] ERROR: relation "goose_db_version" does not exist at character 3612622026-09-22 08:50:42.195 UTC [74210] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12632026/09/22 08:50:42 OK 20260905000000_add_claims.sql (64.18ms)12642026/09/22 08:50:42 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjdhZTA4NDczLWNlYTctNDlhYS1iMDQ1LTY0OTAyYzRiMzQ0NHgxNzkwMDY3MDQwNTAxNDA5MDAw parts=1012652026/09/22 08:50:42 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete12662026/09/22 08:50:42 OK 20260905000000_add_claims.sql (45.29ms)12672026/09/22 08:50:42 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign12682026/09/22 08:50:42 INFO Signed narinfos id=2 count=112692026/09/22 08:50:42 INFO Uploading 1 narinfos12702026/09/22 08:50:42 WARN Failed to register uploaded object key=xqzcswvlwj4kfr06n4n9b6f0d7zgyww8.ls error="server returned 404: 404 page not found\n"12712026/09/22 08:50:42 INFO Completed upload id=112722026/09/22 08:50:42 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000012732026/09/22 08:50:42 INFO Received uploads request method=POST path=/api/pending_closures12742026/09/22 08:50:42 INFO Starting cleanup of old closures method=DELETE path=/api/closures12752026/09/22 08:50:42 INFO Aborted multipart uploads count=012762026/09/22 08:50:42 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete12772026/09/22 08:50:42 WARN Failed to register uploaded object key=xqzcswvlwj4kfr06n4n9b6f0d7zgyww8.narinfo error="server returned 404: 404 page not found\n"12782026/09/22 08:50:42 INFO Completed upload id=212792026/09/22 08:50:42 INFO Upload complete. (148ms)12802026/09/22 08:50:42 OK 20260920000000_drop_claims.sql (24.7ms)12812026/09/22 08:50:42 goose: successfully migrated database to version: 2026092000000012822026/09/22 08:50:42 OK 1_commit_pending_closure.sql (967.08µs)12832026/09/22 08:50:42 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=012842026/09/22 08:50:42 OK 2_object_stats_trigger.sql (306.92µs)12852026/09/22 08:50:42 goose: up to current file version: 212862026/09/22 08:50:42 OK 20260920000000_drop_claims.sql (30ms)12872026/09/22 08:50:42 goose: successfully migrated database to version: 2026092000000012882026/09/22 08:50:42 OK 1_commit_pending_closure.sql (892.21µs)12892026/09/22 08:50:42 OK 2_object_stats_trigger.sql (227.92µs)12902026/09/22 08:50:42 goose: up to current file version: 212912026/09/22 08:50:42 INFO Vacuumed table table=pending_closures12922026/09/22 08:50:42 OK 20241026095416_initial_model.sql (138.96ms)12932026/09/22 08:50:42 INFO Received create pin request method=POST path=/api/pins/myapp12942026/09/22 08:50:42 INFO Vacuumed table table=pending_objects12952026/09/22 08:50:42 OK 20251210153512_drop_unused_gin_index.sql (16ms)12962026/09/22 08:50:42 OK 20251218171726_add_pins.sql (12.46ms)12972026/09/22 08:50:42 INFO Vacuumed table table=multipart_uploads12982026/09/22 08:50:42 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-73934-291241723/TestPinProtectsFromGC2416160635/001/store/n4fkr8qph5bxr6nd33xc80319s5nmfqb-pinned-file.txt narinfo_key=n4fkr8qph5bxr6nd33xc80319s5nmfqb.narinfo12992026/09/22 08:50:42 INFO Starting cleanup of old closures method=DELETE path=/api/closures13002026/09/22 08:50:42 INFO Garbage collection started13012026/09/22 08:50:42 INFO Aborted multipart uploads count=013022026/09/22 08:50:42 WARN Force mode enabled - objects will be deleted immediately without grace period13032026/09/22 08:50:42 INFO Vacuumed table table=closures13042026/09/22 08:50:42 OK 20260628120000_add_object_size_and_stats.sql (47.39ms)13052026/09/22 08:50:42 INFO Vacuumed table table=objects13062026/09/22 08:50:42 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001307--- PASS: TestService_createPendingClosureHandler (4.47s)1308=== CONT TestGracefulShutdownDrainsInflight13092026/09/22 08:50:42 INFO Starting HTTP server address=127.0.0.1:5312613102026/09/22 08:50:42 INFO Shutdown signal received, draining in-flight requests timeout=10s13112026/09/22 08:50:42 OK 20260905000000_add_claims.sql (59.77ms)13122026/09/22 08:50:42 OK 20260920000000_drop_claims.sql (29.17ms)13132026/09/22 08:50:42 goose: successfully migrated database to version: 2026092000000013142026/09/22 08:50:42 OK 1_commit_pending_closure.sql (967.54µs)13152026/09/22 08:50:42 OK 2_object_stats_trigger.sql (238.04µs)13162026/09/22 08:50:42 goose: up to current file version: 21317--- PASS: TestGracefulShutdownDrainsInflight (0.07s)1318=== CONT TestService_NativeMTLS13192026/09/22 08:50:42 OK 20241026095416_initial_model.sql (202.07ms)13202026/09/22 08:50:42 OK 20251210153512_drop_unused_gin_index.sql (10.89ms)13212026/09/22 08:50:42 OK 20251218171726_add_pins.sql (13.32ms)13222026/09/22 08:50:42 INFO lead: acquired remote=192.0.2.1:123413232026/09/22 08:50:42 OK 20260628120000_add_object_size_and_stats.sql (37.52ms)13242026/09/22 08:50: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=013252026/09/22 08:50:42 INFO Vacuumed table table=pending_closures13262026/09/22 08:50:42 OK 20260905000000_add_claims.sql (64.3ms)13272026/09/22 08:50:42 OK 20260920000000_drop_claims.sql (13.4ms)13282026/09/22 08:50:42 goose: successfully migrated database to version: 2026092000000013292026/09/22 08:50:42 INFO Vacuumed table table=pending_objects13302026/09/22 08:50:42 INFO Vacuumed table table=multipart_uploads13312026/09/22 08:50:42 OK 1_commit_pending_closure.sql (1.32ms)13322026/09/22 08:50:42 OK 2_object_stats_trigger.sql (218.96µs)13332026/09/22 08:50:42 goose: up to current file version: 213342026/09/22 08:50:42 INFO Vacuumed table table=closures13352026/09/22 08:50:42 INFO lead: released remote=192.0.2.1:123413362026/09/22 08:50:42 INFO Vacuumed table table=objects13372026/09/22 08:50:42 INFO lead: acquired remote=192.0.2.1:123413382026/09/22 08:50:42 INFO lead: released remote=192.0.2.1:12341339--- PASS: TestLeadElectsOneAndHandsOver (3.20s)1340=== CONT TestMetricsInventory13412026-09-22 08:50:42.807 UTC [74228] ERROR: relation "goose_db_version" does not exist at character 3613422026-09-22 08:50:42.807 UTC [74228] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC13432026/09/22 08:50:42 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"13442026/09/22 08:50:42 INFO Received uploads request method=POST path=/api/pending_closures13452026/09/22 08:50:42 INFO Received uploads request method=POST path=/api/pending_closures13462026/09/22 08:50:42 INFO Uploading 54 paths to 127.0.0.1 (0 already cached)13472026/09/22 08:50:42 INFO Uploading 3h9kj1p7r77q4v7zxi1gqq3gssa33qcd-nix-store-2.34.8 (5.2MB)13482026/09/22 08:50:42 INFO Uploading mc2fh87sg8s44nhdwxmbrgj618x6yspa-sqlite-3.53.3 (6.1MB)13492026/09/22 08:50:42 INFO Uploading 4i8nx3hxaw6n6m58jwwh4hb4zc2pdigx-nix-fetchers-2.34.8 (1.4MB)13502026/09/22 08:50:42 INFO Uploading g8s95hxz9j9mcka9vp1nzkrjbm95v2iq-libsodium-1.0.22-unstable-2026-08-13 (533.6KB)13512026/09/22 08:50:42 INFO Uploading sg2g4rkd7b5vxz3jx6ly1rb6wchjsjpw-libunistring-1.4.2 (2.0MB)13522026/09/22 08:50:42 INFO Uploading zrkd5lwn2c1a630ypjif3ijggd4npd5a-nix-2.34.8 (3.6MB)13532026/09/22 08:50:42 INFO Uploading w2nw8isfr6vaqw8yyg5kznpx1npd35ps-openssl-3.6.4 (6.3MB)13542026/09/22 08:50:42 INFO Uploading yavsyjx7p391ik6pv5j5wbl26427gfn8-aws-c-event-stream-0.7.0 (189.5KB)13552026/09/22 08:50:42 INFO Uploading wkn7va8gvydb8my66ilj5bk3lc5q52xd-boost-1.89.0 (10.6MB)13562026/09/22 08:50:42 INFO Uploading b5siikh4m7fv90p3p68h6fmd9l3jh276-nghttp3-1.16.0 (199.6KB)13572026/09/22 08:50:42 INFO Uploading nfcmif2wryzzgp3h24909w1lp42c25i1-libpsl-0.21.5 (155.3KB)13582026/09/22 08:50:42 WARN Failed to register uploaded object key=nar/1nrghf2kdwgdw4a5w56f7iknxhbs4lxlv5xz9d7rdv7rywpvxdvq.nar.zst error="server returned 404: 404 page not found\n"13592026/09/22 08:50:42 WARN Failed to register uploaded object key=nar/01q970lafjbclyra7j77161jh2v48fcc8729s96agk1fcwxshsx9.nar.zst error="server returned 404: 404 page not found\n"13602026/09/22 08:50:42 INFO Uploading j2akxpyimshx8njg05b2fj0c6yvgnpjd-libssh2-1.11.1 (293.6KB)13612026/09/22 08:50:42 INFO Uploading g6b5gbjwqp56d72wsxz7a4pjrsdhab1k-aws-checksums-0.2.7 (112.1KB)13622026/09/22 08:50:42 WARN Failed to register uploaded object key=log/j196vkhjw3izj16243537qccx53ah81w-nix-flake-2.34.8.drv error="server returned 404: 404 page not found\n"13632026/09/22 08:50:42 WARN Failed to register uploaded object key=log/m7kka6j9z5hvw8cq0gdrs34dfq0m8dc3-nix-main-2.34.8.drv error="server returned 404: 404 page not found\n"13642026/09/22 08:50:42 WARN Failed to register uploaded object key=nar/1zs5rhz0s280p981p1vfl39cb200k40zb05vc76lmd5jih1d3yqc.nar.zst error="server returned 404: 404 page not found\n"13652026/09/22 08:50:42 OK 20241026095416_initial_model.sql (146.03ms)13662026/09/22 08:50:42 WARN Failed to register uploaded object key=log/mq2sqhrnak8c47sj1wsljnijyz1hb1ks-nix-cmd-2.34.8.drv error="server returned 404: 404 page not found\n"13672026/09/22 08:50:43 INFO Uploading msikdwgdb7qnfbc4izcr4v7fznqvfgfl-s2n-tls-1.7.8 (1.1MB)13682026/09/22 08:50:43 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst13692026/09/22 08:50:43 INFO Received uploads request method=POST path=/api/pending_closures1370--- PASS: TestPresignedUploadRegisteredBeforeCommit (3.37s)1371=== CONT TestNARDeduplicationMetadataUploadBug13722026/09/22 08:50:43 OK 20251210153512_drop_unused_gin_index.sql (20.44ms)13732026/09/22 08:50:43 INFO Uploading 2zm5grik16b5vb89i3da7cnm8f5cgqdp-libresolv-96 (214.6KB)13742026/09/22 08:50:43 WARN Failed to register uploaded object key=b5siikh4m7fv90p3p68h6fmd9l3jh276.ls error="server returned 404: 404 page not found\n"13752026/09/22 08:50:43 WARN Failed to register uploaded object key=log/nb9w83srwhadmbxjf7dbf90yj1dpd0hz-nix-2.34.8.drv error="server returned 404: 404 page not found\n"13762026/09/22 08:50:43 INFO Uploading 3vm49hzc81zp6imis2jk847gcgk6pqy0-pcre2-10.48 (2.2MB)13772026/09/22 08:50:43 INFO Uploading fd67lc7y7q9pvb8i4aghy8slyr45dz1g-bzip2-1.0.8 (101.8KB)13782026/09/22 08:50:43 INFO Uploading ckr723m6sv93x0q7b9qvfdjds4v3b401-nix-flake-2.34.8 (888.3KB)13792026/09/22 08:50:43 WARN Failed to register uploaded object key=log/npjjivvkb4si6vbglva8gi3hi6fd9yc2-nix-expr-2.34.8.drv error="server returned 404: 404 page not found\n"13802026/09/22 08:50:43 INFO Uploading jx1x1fs1h50x7idhj06v124ls8d98qaw-libiconv-1.19 (1.1MB)13812026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0bkzix3axqa2srng2kd8jxfvb9jnkkzybjcg7hipp5wabcxwrwk4.nar.zst error="server returned 404: 404 page not found\n"13822026/09/22 08:50:43 WARN Failed to register uploaded object key=nfcmif2wryzzgp3h24909w1lp42c25i1.ls error="server returned 404: 404 page not found\n"13832026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0bbg6dpqm0nf14x60qlb4mi1k8jy1xzrjlbjlvq8b65k7n45h1ab.nar.zst error="server returned 404: 404 page not found\n"13842026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0877ir8j8h07p9ri69549gr4kn44skwdlqqhls1818n54cfkj5yp.nar.zst error="server returned 404: 404 page not found\n"13852026/09/22 08:50:43 WARN Failed to register uploaded object key=yavsyjx7p391ik6pv5j5wbl26427gfn8.ls error="server returned 404: 404 page not found\n"13862026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0nmwa4318xc8zihja3g8j2yhz2qzh5n10qv5ngg27vg6r6ssbhvi.nar.zst error="server returned 404: 404 page not found\n"13872026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1bkaqnqp036x17ws2p5g90p3yd4d2lr3rfkvjmb76g3jm8lmw6wa.nar.zst error="server returned 404: 404 page not found\n"13882026/09/22 08:50:43 OK 20251218171726_add_pins.sql (35.78ms)13892026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0hpl5awjwm7mhfs6plfxq4xdc5v4ax3cwy1smg648rm8bkbqxg59.nar.zst error="server returned 404: 404 page not found\n"13902026/09/22 08:50:43 INFO Uploading ls125wfdax9gk2ryq7fgzrncpi6x5v2s-libiconv-115.100.1 (43.7MB)13912026/09/22 08:50:43 INFO Uploading aq7lgbmcm7v5zsm2p4lyr2ngwplxxy78-aws-crt-cpp-0.34.3 (763.8KB)13922026/09/22 08:50:43 INFO Uploading zgj1hf1lfv9yabwsnp68rnq7bryf6d9b-nghttp2-1.70.0-lib (207.4KB)13932026/09/22 08:50:43 INFO Uploading njlvbnsaj76jsqg45w7v4540riqlcpx0-nix-cmd-2.34.8 (1018.5KB)13942026/09/22 08:50:43 WARN Failed to register uploaded object key=g6b5gbjwqp56d72wsxz7a4pjrsdhab1k.ls error="server returned 404: 404 page not found\n"13952026/09/22 08:50:43 WARN Failed to register uploaded object key=j2akxpyimshx8njg05b2fj0c6yvgnpjd.ls error="server returned 404: 404 page not found\n"13962026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1xvspakplscwf5jzzn21kl0c3wcnddcvkdwrqxzlxgf53hcrkzii.nar.zst error="server returned 404: 404 page not found\n"13972026/09/22 08:50:43 WARN Failed to register uploaded object key=4i8nx3hxaw6n6m58jwwh4hb4zc2pdigx.ls error="server returned 404: 404 page not found\n"13982026/09/22 08:50:43 WARN Failed to register uploaded object key=sg2g4rkd7b5vxz3jx6ly1rb6wchjsjpw.ls error="server returned 404: 404 page not found\n"13992026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1y7r1fgpzd7wl7apmz439ihl0d3ib72qrfd747wfl2kg4y3bc9nf.nar.zst error="server returned 404: 404 page not found\n"14002026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/00r67k4b4ca4bbfgr2kyywal8x3pysy63l2kk8diw8d3jvg4x5qy.nar.zst error="server returned 404: 404 page not found\n"14012026/09/22 08:50:43 INFO Uploading zys7fi4p7cxyxzzavxcddz91c5b3g5pc-publicsuffix-list-0-unstable-2026-09-02 (330.4KB)14022026/09/22 08:50:43 WARN Failed to register uploaded object key=g8s95hxz9j9mcka9vp1nzkrjbm95v2iq.ls error="server returned 404: 404 page not found\n"14032026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/19qh14kxg1hizqnq556p2xi3ihzca8narb9sj9dibx7sbx3hi5c7.nar.zst error="server returned 404: 404 page not found\n"14042026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0j7sz8d0mrd7zqxxpna8l4d2ag4v63kpilyac1vlr55djcxv8imh.nar.zst error="server returned 404: 404 page not found\n"14052026/09/22 08:50:43 OK 20260628120000_add_object_size_and_stats.sql (61.1ms)14062026/09/22 08:50:43 INFO Uploading pcj4n9gxz0hi2f71cchw2h8zjhv3r013-aws-c-mqtt-0.13.3 (495.4KB)14072026/09/22 08:50:43 WARN Failed to register uploaded object key=zrkd5lwn2c1a630ypjif3ijggd4npd5a.ls error="server returned 404: 404 page not found\n"14082026/09/22 08:50:43 INFO Uploading d60c1h8784x4yd63g18jgh6jg60pppxg-krb5-1.22.2-lib (2.5MB)14092026/09/22 08:50:43 WARN Failed to register uploaded object key=2zm5grik16b5vb89i3da7cnm8f5cgqdp.ls error="server returned 404: 404 page not found\n"14102026/09/22 08:50:43 INFO Uploading 6l30l0dgk1vscbvbrbg4nj6889c74ci1-zstd-1.5.7 (907.2KB)14112026/09/22 08:50:43 INFO Uploading 54gk8c07c9gadvs98cmwnvkj0xn3nbzw-xz-5.8.3 (180.7KB)14122026/09/22 08:50:43 WARN Failed to register uploaded object key=3h9kj1p7r77q4v7zxi1gqq3gssa33qcd.ls error="server returned 404: 404 page not found\n"14132026/09/22 08:50:43 WARN Failed to register uploaded object key=mc2fh87sg8s44nhdwxmbrgj618x6yspa.ls error="server returned 404: 404 page not found\n"14142026/09/22 08:50:43 INFO Uploading 1in3q9rzaw1jk4zdnh6k2b187ahm9zac-llhttp-9.4.3 (92.5KB)14152026/09/22 08:50:43 INFO Uploading ggihpyvbd7j20mi9f6hp5smqvdsmfbi5-aws-c-cal-0.9.2 (152.4KB)14162026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/15c5qp7n05g776qwd7dwd32595b384cqkjl1i09zymn2hh71gc87.nar.zst error="server returned 404: 404 page not found\n"14172026/09/22 08:50:43 WARN Failed to register uploaded object key=fd67lc7y7q9pvb8i4aghy8slyr45dz1g.ls error="server returned 404: 404 page not found\n"14182026/09/22 08:50:43 WARN Failed to register uploaded object key=msikdwgdb7qnfbc4izcr4v7fznqvfgfl.ls error="server returned 404: 404 page not found\n"14192026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0qavnsfglakdlg2bqqbjslqqpr1x4bnxgd3hzcxcgr7apvj53kcf.nar.zst error="server returned 404: 404 page not found\n"14202026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1h23cl2d6bdql57kl5xywqmr74pbl5lj5i4y0hq13ha51g1fn33i.nar.zst error="server returned 404: 404 page not found\n"14212026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/008v3d21lq0lrgcm8cv7r8fy65mv7m456x2np68xwds3yspdb9b0.nar.zst error="server returned 404: 404 page not found\n"14222026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0w2j5ysy1pm9bzrnbma8x62jfhgbkyjqw5v8l10gnjg9y0gkdgfz.nar.zst error="server returned 404: 404 page not found\n"14232026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/04i2ykr8bg2ws1cm44cy7hv403aqxvsj3pz78qnpvmlq7ag6fkzg.nar.zst error="server returned 404: 404 page not found\n"14242026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0k7hfchbmvdg6k4rf0b3fk3y9p5lrqzhplzllpjxfiv077i5fnqa.nar.zst error="server returned 404: 404 page not found\n"14252026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/023w5l7s7pvkbzbs1v496b7ip3b7gwp12s7fhbhgdyiig8jm88y4.nar.zst error="server returned 404: 404 page not found\n"14262026/09/22 08:50:43 INFO Uploading q51dqj5sk0gw40bjsbz202cjrzbx1678-libidn2-2.3.8 (236.8KB)14272026/09/22 08:50:43 WARN Failed to register uploaded object key=ckr723m6sv93x0q7b9qvfdjds4v3b401.ls error="server returned 404: 404 page not found\n"14282026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0m2wg98b5s3vn0fsmxwnliv7a6f5dc4mscpzgqabkpw9j1rf3sz1.nar.zst error="server returned 404: 404 page not found\n"14292026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1qk203mxqilk13z8wkb91rpqnardlf17s3f7dcn7kcr8jhrmk879.nar.zst error="server returned 404: 404 page not found\n"14302026/09/22 08:50:43 INFO Uploading flrdr2qx33ka42z1y2gxj6y2m4pfm5kw-libxml2-2.15.4 (1.2MB)14312026/09/22 08:50:43 INFO Uploading lmd946ga64fckh7r7wnsbsksi1g0qsk6-brotli-1.2.0-lib (953.1KB)14322026/09/22 08:50:43 INFO Uploading 5dbjy4q1f1wybp14q69i28p2z6i8nh3b-aws-c-common-0.12.4 (1.0MB)14332026/09/22 08:50:43 INFO Uploading ws7mcyk061m4fwl0v62vh8dc8x0rvznc-nix-expr-2.34.8 (3.2MB)14342026/09/22 08:50:43 WARN Failed to register uploaded object key=zgj1hf1lfv9yabwsnp68rnq7bryf6d9b.ls error="server returned 404: 404 page not found\n"14352026/09/22 08:50:43 WARN Failed to register uploaded object key=w2nw8isfr6vaqw8yyg5kznpx1npd35ps.ls error="server returned 404: 404 page not found\n"14362026/09/22 08:50:43 WARN Failed to register uploaded object key=jx1x1fs1h50x7idhj06v124ls8d98qaw.ls error="server returned 404: 404 page not found\n"14372026/09/22 08:50:43 WARN Failed to register uploaded object key=3vm49hzc81zp6imis2jk847gcgk6pqy0.ls error="server returned 404: 404 page not found\n"14382026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0z9prwv51j0iwa5gp1r9ndc9bx4579xwfb0r80xv46ir058qa6wz.nar.zst error="server returned 404: 404 page not found\n"14392026/09/22 08:50:43 OK 20260905000000_add_claims.sql (93.89ms)14402026/09/22 08:50:43 INFO Uploading 4ic5f7a8ynsalz74879ldncvd0cwkdf7-nix-util-2.34.8 (2.0MB)14412026/09/22 08:50:43 WARN Failed to register uploaded object key=zys7fi4p7cxyxzzavxcddz91c5b3g5pc.ls error="server returned 404: 404 page not found\n"14422026/09/22 08:50:43 INFO Uploading nlvsvpijwfnqzcpg01m8ygbyg9aazix9-libgit2-1.9.7-lib (1.2MB)14432026/09/22 08:50:43 INFO Uploading bfwx8xq1sfhh9y14jvz571561ljjf3av-libblake3-1.8.7 (53.1KB)14442026/09/22 08:50:43 WARN Failed to register uploaded object key=njlvbnsaj76jsqg45w7v4540riqlcpx0.ls error="server returned 404: 404 page not found\n"14452026/09/22 08:50:43 WARN Failed to register uploaded object key=1in3q9rzaw1jk4zdnh6k2b187ahm9zac.ls error="server returned 404: 404 page not found\n"14462026/09/22 08:50:43 INFO Uploading x4whajqp1nxcza087sr10696mwhk65kx-aws-c-sdkutils-0.2.4 (164.4KB)14472026/09/22 08:50:43 WARN Failed to register uploaded object key=54gk8c07c9gadvs98cmwnvkj0xn3nbzw.ls error="server returned 404: 404 page not found\n"14482026/09/22 08:50:43 OK 20260920000000_drop_claims.sql (52.05ms)14492026/09/22 08:50:43 INFO Uploading bpnyprhzfwr4gw1mdgpkwgrd83ibj8dk-boehm-gc-8.2.12 (339.9KB)14502026/09/22 08:50:43 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14512026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0aycll1ydnikmj3756amvhsq8rfz273js8bfw7c4sgr3m8drz6hb.nar.zst error="server returned 404: 404 page not found\n"14522026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1h1nsgg6zg97cfcfpqn7jip4v29hi4mhd97hp0a0pdm9xjhinkjc.nar.zst error="server returned 404: 404 page not found\n"14532026/09/22 08:50:43 WARN Failed to register uploaded object key=aq7lgbmcm7v5zsm2p4lyr2ngwplxxy78.ls error="server returned 404: 404 page not found\n"14542026/09/22 08:50:43 goose: successfully migrated database to version: 2026092000000014552026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1h5f4cgqzi9bl8ci44qpgpb94bfn24s898vh8nbw8wsizicp38cl.nar.zst error="server returned 404: 404 page not found\n"14562026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/09b95kc94mwjsbmqn8lkm0yfkswcv5iridyj338ggf92cpc89syk.nar.zst error="server returned 404: 404 page not found\n"14572026/09/22 08:50:43 OK 1_commit_pending_closure.sql (1.31ms)14582026/09/22 08:50:43 OK 2_object_stats_trigger.sql (253.08µs)14592026/09/22 08:50:43 goose: up to current file version: 214602026/09/22 08:50:43 INFO Uploading 5ywjd7fkx35g3g7m3vhfb8lq5i02znrn-libarchive-3.8.9-lib (815.1KB)14612026/09/22 08:50:43 WARN Failed to register uploaded object key=ggihpyvbd7j20mi9f6hp5smqvdsmfbi5.ls error="server returned 404: 404 page not found\n"14622026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1lmx67s5gwlycfjwfj9n04qwfw8xxwbl5g4hkinw3n3lrg4kfn3y.nar.zst error="server returned 404: 404 page not found\n"14632026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1p899mxg7q2jv9xv0ni7bzix5lzzl4dfd1kba52rwxharnh93cm7.nar.zst error="server returned 404: 404 page not found\n"14642026/09/22 08:50:43 INFO Uploading hgc72zzfwbnrp5qyrjkvxrlgfcnr381q-aws-c-s3-0.8.7 (337.4KB)14652026/09/22 08:50:43 WARN Failed to register uploaded object key=pcj4n9gxz0hi2f71cchw2h8zjhv3r013.ls error="server returned 404: 404 page not found\n"14662026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/069sa63xrync9y3bilxgg5zfjb07cwmgc0rdrk1k9rr0d6kfnyfs.nar.zst error="server returned 404: 404 page not found\n"14672026/09/22 08:50:43 INFO Uploading 6c7nrda2k1s1qq8c97rckhm3iy61hkz9-curl-8.22.0 (996.0KB)14682026/09/22 08:50:43 WARN Failed to register uploaded object key=d60c1h8784x4yd63g18jgh6jg60pppxg.ls error="server returned 404: 404 page not found\n"14692026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0asf3l24p9nmnc76vc5iwp8vq5a7bf1mg2qadli77ill8kf6m69v.nar.zst error="server returned 404: 404 page not found\n"14702026/09/22 08:50:43 INFO Uploading 8269z1k2lcr7c1hskyb48kw6dgb325y6-ngtcp2-1.23.0 (425.8KB)14712026/09/22 08:50:43 INFO Uploading a0dl4ap79wj5m0c8cla39py0x84adj5c-aws-c-http-0.11.0 (563.3KB)14722026/09/22 08:50:43 WARN Failed to register uploaded object key=6l30l0dgk1vscbvbrbg4nj6889c74ci1.ls error="server returned 404: 404 page not found\n"14732026/09/22 08:50:43 WARN Failed to register uploaded object key=q51dqj5sk0gw40bjsbz202cjrzbx1678.ls error="server returned 404: 404 page not found\n"14742026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0wh4mqs3ld0avm5sd59p154gwcn3arfky7gs3mm7xp99szr10a7n.nar.zst error="server returned 404: 404 page not found\n"14752026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0gks3dp6fs59hgcqq7j4s12461d34grni8yg8ll0akcy32qrhszg.nar.zst error="server returned 404: 404 page not found\n"14762026/09/22 08:50:43 INFO Uploading bj08vjy1ywpf0jhwk5c20hcxrrfnyv0b-onetbb-2022.3.0 (600.7KB)14772026/09/22 08:50:43 INFO Uploading pw9nb6qfihpl2g4kz96h4h59722j2wf2-editline-1.17.1-unstable-2025-05-24 (91.0KB)14782026/09/22 08:50:43 INFO Uploading sblmiq5q0agpvr4vlppd8s056gs05xps-hwloc-2.14.0-lib (305.8KB)14792026/09/22 08:50:43 WARN Failed to register uploaded object key=lmd946ga64fckh7r7wnsbsksi1g0qsk6.ls error="server returned 404: 404 page not found\n"14802026/09/22 08:50:43 WARN Failed to register uploaded object key=bfwx8xq1sfhh9y14jvz571561ljjf3av.ls error="server returned 404: 404 page not found\n"14812026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1y8vvhhkmpc8ki8mm3121ksjdjidrnf7f9n78235mzg45nz09knw.nar.zst error="server returned 404: 404 page not found\n"14822026/09/22 08:50:43 WARN Failed to register uploaded object key=x4whajqp1nxcza087sr10696mwhk65kx.ls error="server returned 404: 404 page not found\n"14832026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0d1vs46cq596q45vavajng1x02wl4db6rhpwkmmhngykc9fzgikj.nar.zst error="server returned 404: 404 page not found\n"14842026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/161s0fqf6il56yr7bsl02qnn5wjwpj4m7bpm4p0hra9ziqg8hna7.nar.zst error="server returned 404: 404 page not found\n"14852026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1qv7v2hxq98jinf8mhizpjqjgg45rigl0afgq26b33z4bfynkmdy.nar.zst error="server returned 404: 404 page not found\n"14862026/09/22 08:50:43 INFO Completed multipart upload object_key=nar/12mxikxgrrp90s6qqs17vxwd1an1nlfaqrwghgs15s2dw6k6lsl9.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjhjNWI4ODEzLThjMzctNGYzZC1hMzI3LTc1YjllYjMwZmQzOHgxNzkwMDY3MDQyOTA5NTMxMDAw parts=114872026/09/22 08:50:43 INFO Uploading jqb3pb1vbcpdqvd213jv72z8rk804f6i-aws-c-compression-0.3.1 (66.1KB)14882026/09/22 08:50:43 INFO Uploading 7sxnsmmrypnlv3pq78xc9ihqbq4fgrqs-lowdown-3.0.1-lib (291.9KB)14892026/09/22 08:50:43 WARN Failed to register uploaded object key=ws7mcyk061m4fwl0v62vh8dc8x0rvznc.ls error="server returned 404: 404 page not found\n"14902026/09/22 08:50:43 INFO Received uploads request method=POST path=/api/pending_closures14912026/09/22 08:50:43 WARN Failed to register uploaded object key=flrdr2qx33ka42z1y2gxj6y2m4pfm5kw.ls error="server returned 404: 404 page not found\n"14922026/09/22 08:50:43 INFO Uploading s0y18hp1bzr1vwrasdcrmvkj6py2izpi-zlib-1.3.2 (107.7KB)14932026/09/22 08:50:43 INFO Uploading mqch29qcvvlznanqmq2xrymaf6j7yycd-aws-c-auth-0.9.1 (362.8KB)14942026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/15dwrb2wj0398jpddkmbcf2ryabmni9fyw66zw9bzzi7lmakbckz.nar.zst error="server returned 404: 404 page not found\n"14952026/09/22 08:50:43 WARN Failed to register uploaded object key=5dbjy4q1f1wybp14q69i28p2z6i8nh3b.ls error="server returned 404: 404 page not found\n"14962026/09/22 08:50:43 WARN Failed to register uploaded object key=4ic5f7a8ynsalz74879ldncvd0cwkdf7.ls error="server returned 404: 404 page not found\n"14972026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1ldbvr9r5m9hmilb72qwxvjs5ydam2yl2v4dknn7jnv02jz12p13.nar.zst error="server returned 404: 404 page not found\n"14982026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/150r1gq2kyywa7nrsrsqf4hi5p0zgbzhjibwdzlhb8kbkyklzpf9.nar.zst error="server returned 404: 404 page not found\n"14992026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1w3x6ah9k1c7myq0gga131y7c069qq45ww6xj9nsaxs445fqwpy6.nar.zst error="server returned 404: 404 page not found\n"15002026/09/22 08:50:43 INFO Uploading gw27wld41dr4n128npfn4bq8qhnss0mw-aws-c-io-0.27.2 (674.3KB)15012026/09/22 08:50:43 WARN Failed to register uploaded object key=hgc72zzfwbnrp5qyrjkvxrlgfcnr381q.ls error="server returned 404: 404 page not found\n"15022026/09/22 08:50:43 INFO Uploading 057nrp7k765ln97xk8f1xgvfhqi3lz0b-nix-main-2.34.8 (364.7KB)15032026/09/22 08:50:43 WARN Failed to register uploaded object key=bpnyprhzfwr4gw1mdgpkwgrd83ibj8dk.ls error="server returned 404: 404 page not found\n"15042026/09/22 08:50:43 WARN Failed to register uploaded object key=5ywjd7fkx35g3g7m3vhfb8lq5i02znrn.ls error="server returned 404: 404 page not found\n"15052026/09/22 08:50:43 WARN Failed to register uploaded object key=nlvsvpijwfnqzcpg01m8ygbyg9aazix9.ls error="server returned 404: 404 page not found\n"15062026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/06rvjndviywcqdw6nfvl0jrc7hygb5ybdpw6w7xspwagzclk6dyp.nar.zst error="server returned 404: 404 page not found\n"15072026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0mg745wxflhkyb57pkrxs191kah3w4mi9bkbpyj7dz292xhpbs36.nar.zst error="server returned 404: 404 page not found\n"15082026/09/22 08:50:43 WARN Failed to register uploaded object key=wkn7va8gvydb8my66ilj5bk3lc5q52xd.ls error="server returned 404: 404 page not found\n"15092026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/04vrklc41rgk7l07kdinnyqmgr7is61p4ad3wj177ngrma96xfwm.nar.zst error="server returned 404: 404 page not found\n"15102026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/06wf7ky8xkr0hbvg7d94m4dnb8a91w2ph41w1kcc10akp5df6kdh.nar.zst error="server returned 404: 404 page not found\n"15112026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/10fr339h07ncsgqj76brvc8fklmjqmsy1dfcbc7r1hkgq1x37ldj.nar.zst error="server returned 404: 404 page not found\n"15122026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1jrzjgd214rava45s42knswy0j78q8qvdrcfynif46ylbfpxln69.nar.zst error="server returned 404: 404 page not found\n"15132026/09/22 08:50:43 WARN Failed to register uploaded object key=8269z1k2lcr7c1hskyb48kw6dgb325y6.ls error="server returned 404: 404 page not found\n"15142026/09/22 08:50:43 WARN Failed to register uploaded object key=pw9nb6qfihpl2g4kz96h4h59722j2wf2.ls error="server returned 404: 404 page not found\n"15152026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0igs3panm2xyxkhqj5yn8pfnayb7107vgqgqrcgf07n8bwwccyw3.nar.zst error="server returned 404: 404 page not found\n"15162026/09/22 08:50:43 WARN Failed to register uploaded object key=6c7nrda2k1s1qq8c97rckhm3iy61hkz9.ls error="server returned 404: 404 page not found\n"15172026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/0g454gfbcl5ywk10h2vls674q8bm7nbpskflmyckamvysccx1r0d.nar.zst error="server returned 404: 404 page not found\n"15182026/09/22 08:50:43 WARN Failed to register uploaded object key=jqb3pb1vbcpdqvd213jv72z8rk804f6i.ls error="server returned 404: 404 page not found\n"15192026/09/22 08:50:43 WARN Failed to register uploaded object key=s0y18hp1bzr1vwrasdcrmvkj6py2izpi.ls error="server returned 404: 404 page not found\n"15202026/09/22 08:50:43 WARN Failed to register uploaded object key=mqch29qcvvlznanqmq2xrymaf6j7yycd.ls error="server returned 404: 404 page not found\n"15212026/09/22 08:50:43 WARN Failed to register uploaded object key=a0dl4ap79wj5m0c8cla39py0x84adj5c.ls error="server returned 404: 404 page not found\n"15222026/09/22 08:50:43 WARN Failed to register uploaded object key=057nrp7k765ln97xk8f1xgvfhqi3lz0b.ls error="server returned 404: 404 page not found\n"15232026/09/22 08:50:43 WARN Failed to register uploaded object key=bj08vjy1ywpf0jhwk5c20hcxrrfnyv0b.ls error="server returned 404: 404 page not found\n"15242026/09/22 08:50:43 WARN Failed to register uploaded object key=7sxnsmmrypnlv3pq78xc9ihqbq4fgrqs.ls error="server returned 404: 404 page not found\n"15252026/09/22 08:50:43 WARN Failed to register uploaded object key=sblmiq5q0agpvr4vlppd8s056gs05xps.ls error="server returned 404: 404 page not found\n"15262026/09/22 08:50:43 INFO Received complete multipart upload request method=POST path=/api/multipart/complete15272026/09/22 08:50:43 WARN Failed to register uploaded object key=nar/1il35w0jm3g76c9xhqsmc204xl4ffhhxai70ngd1wanzxck5i3d4.nar.zst error="server returned 404: 404 page not found\n"15282026-09-22 08:50:43.513 UTC [74234] ERROR: relation "goose_db_version" does not exist at character 3615292026-09-22 08:50:43.513 UTC [74234] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC15302026/09/22 08:50:43 WARN Failed to register uploaded object key=gw27wld41dr4n128npfn4bq8qhnss0mw.ls error="server returned 404: 404 page not found\n"15312026/09/22 08:50:43 INFO Completed multipart upload object_key=nar/1n2mvw3rdc19av2bby7zxrs566yg5pvbz2j05ffpqckjw7ck4jm4.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjdlZTMxMzhiLTgyOTctNGQ2NC04ODBjLWY2Y2MzM2I2YTVmNHgxNzkwMDY3MDQyOTA5OTUwMDAw parts=115322026/09/22 08:50:43 WARN Failed to register uploaded object key=ls125wfdax9gk2ryq7fgzrncpi6x5v2s.ls error="server returned 404: 404 page not found\n"15332026/09/22 08:50:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15342026/09/22 08:50:43 INFO Signed narinfos id=1 count=5415352026/09/22 08:50:43 INFO Uploading 54 narinfos15362026/09/22 08:50:43 WARN Failed to register uploaded object key=pcj4n9gxz0hi2f71cchw2h8zjhv3r013.narinfo error="server returned 404: 404 page not found\n"15372026/09/22 08:50:43 WARN Failed to register uploaded object key=ggihpyvbd7j20mi9f6hp5smqvdsmfbi5.narinfo error="server returned 404: 404 page not found\n"15382026/09/22 08:50:43 WARN Failed to register uploaded object key=s0y18hp1bzr1vwrasdcrmvkj6py2izpi.narinfo error="server returned 404: 404 page not found\n"15392026/09/22 08:50:43 WARN Failed to register uploaded object key=flrdr2qx33ka42z1y2gxj6y2m4pfm5kw.narinfo error="server returned 404: 404 page not found\n"15402026/09/22 08:50:43 WARN Failed to register uploaded object key=w2nw8isfr6vaqw8yyg5kznpx1npd35ps.narinfo error="server returned 404: 404 page not found\n"15412026/09/22 08:50:43 WARN Failed to register uploaded object key=3vm49hzc81zp6imis2jk847gcgk6pqy0.narinfo error="server returned 404: 404 page not found\n"15422026/09/22 08:50:43 WARN Failed to register uploaded object key=a0dl4ap79wj5m0c8cla39py0x84adj5c.narinfo error="server returned 404: 404 page not found\n"15432026/09/22 08:50:43 WARN Failed to register uploaded object key=lmd946ga64fckh7r7wnsbsksi1g0qsk6.narinfo error="server returned 404: 404 page not found\n"15442026/09/22 08:50:43 WARN Failed to register uploaded object key=j2akxpyimshx8njg05b2fj0c6yvgnpjd.narinfo error="server returned 404: 404 page not found\n"15452026/09/22 08:50:43 WARN Failed to register uploaded object key=wkn7va8gvydb8my66ilj5bk3lc5q52xd.narinfo error="server returned 404: 404 page not found\n"15462026/09/22 08:50:43 WARN Failed to register uploaded object key=mqch29qcvvlznanqmq2xrymaf6j7yycd.narinfo error="server returned 404: 404 page not found\n"15472026/09/22 08:50:43 WARN Failed to register uploaded object key=jqb3pb1vbcpdqvd213jv72z8rk804f6i.narinfo error="server returned 404: 404 page not found\n"15482026/09/22 08:50:43 WARN Failed to register uploaded object key=zys7fi4p7cxyxzzavxcddz91c5b3g5pc.narinfo error="server returned 404: 404 page not found\n"15492026/09/22 08:50:43 WARN Failed to register uploaded object key=3h9kj1p7r77q4v7zxi1gqq3gssa33qcd.narinfo error="server returned 404: 404 page not found\n"15502026/09/22 08:50:43 WARN Failed to register uploaded object key=yavsyjx7p391ik6pv5j5wbl26427gfn8.narinfo error="server returned 404: 404 page not found\n"15512026/09/22 08:50:43 WARN Failed to register uploaded object key=5ywjd7fkx35g3g7m3vhfb8lq5i02znrn.narinfo error="server returned 404: 404 page not found\n"15522026/09/22 08:50:43 WARN Failed to register uploaded object key=54gk8c07c9gadvs98cmwnvkj0xn3nbzw.narinfo error="server returned 404: 404 page not found\n"15532026/09/22 08:50:43 WARN Failed to register uploaded object key=8269z1k2lcr7c1hskyb48kw6dgb325y6.narinfo error="server returned 404: 404 page not found\n"15542026/09/22 08:50:43 WARN Failed to register uploaded object key=bpnyprhzfwr4gw1mdgpkwgrd83ibj8dk.narinfo error="server returned 404: 404 page not found\n"15552026/09/22 08:50:43 WARN Failed to register uploaded object key=2zm5grik16b5vb89i3da7cnm8f5cgqdp.narinfo error="server returned 404: 404 page not found\n"15562026/09/22 08:50:43 WARN Failed to register uploaded object key=bj08vjy1ywpf0jhwk5c20hcxrrfnyv0b.narinfo error="server returned 404: 404 page not found\n"15572026/09/22 08:50:43 WARN Failed to register uploaded object key=fd67lc7y7q9pvb8i4aghy8slyr45dz1g.narinfo error="server returned 404: 404 page not found\n"15582026/09/22 08:50:43 WARN Failed to register uploaded object key=b5siikh4m7fv90p3p68h6fmd9l3jh276.narinfo error="server returned 404: 404 page not found\n"15592026/09/22 08:50:43 WARN Failed to register uploaded object key=057nrp7k765ln97xk8f1xgvfhqi3lz0b.narinfo error="server returned 404: 404 page not found\n"15602026/09/22 08:50:43 WARN Failed to register uploaded object key=ls125wfdax9gk2ryq7fgzrncpi6x5v2s.narinfo error="server returned 404: 404 page not found\n"15612026/09/22 08:50:43 WARN Failed to register uploaded object key=jx1x1fs1h50x7idhj06v124ls8d98qaw.narinfo error="server returned 404: 404 page not found\n"15622026/09/22 08:50:43 WARN Failed to register uploaded object key=pw9nb6qfihpl2g4kz96h4h59722j2wf2.narinfo error="server returned 404: 404 page not found\n"15632026/09/22 08:50:43 WARN Failed to register uploaded object key=nlvsvpijwfnqzcpg01m8ygbyg9aazix9.narinfo error="server returned 404: 404 page not found\n"15642026/09/22 08:50:43 WARN Failed to register uploaded object key=sblmiq5q0agpvr4vlppd8s056gs05xps.narinfo error="server returned 404: 404 page not found\n"15652026/09/22 08:50:43 WARN Failed to register uploaded object key=sg2g4rkd7b5vxz3jx6ly1rb6wchjsjpw.narinfo error="server returned 404: 404 page not found\n"15662026/09/22 08:50:43 WARN Failed to register uploaded object key=ws7mcyk061m4fwl0v62vh8dc8x0rvznc.narinfo error="server returned 404: 404 page not found\n"15672026/09/22 08:50:43 WARN Failed to register uploaded object key=4i8nx3hxaw6n6m58jwwh4hb4zc2pdigx.narinfo error="server returned 404: 404 page not found\n"15682026/09/22 08:50:43 WARN Failed to register uploaded object key=bfwx8xq1sfhh9y14jvz571561ljjf3av.narinfo error="server returned 404: 404 page not found\n"15692026/09/22 08:50:43 WARN Failed to register uploaded object key=5dbjy4q1f1wybp14q69i28p2z6i8nh3b.narinfo error="server returned 404: 404 page not found\n"15702026/09/22 08:50:43 WARN Failed to register uploaded object key=q51dqj5sk0gw40bjsbz202cjrzbx1678.narinfo error="server returned 404: 404 page not found\n"15712026/09/22 08:50:43 WARN Failed to register uploaded object key=gw27wld41dr4n128npfn4bq8qhnss0mw.narinfo error="server returned 404: 404 page not found\n"15722026/09/22 08:50:43 WARN Failed to register uploaded object key=6l30l0dgk1vscbvbrbg4nj6889c74ci1.narinfo error="server returned 404: 404 page not found\n"15732026/09/22 08:50:43 WARN Failed to register uploaded object key=g8s95hxz9j9mcka9vp1nzkrjbm95v2iq.narinfo error="server returned 404: 404 page not found\n"15742026/09/22 08:50:43 WARN Failed to register uploaded object key=mc2fh87sg8s44nhdwxmbrgj618x6yspa.narinfo error="server returned 404: 404 page not found\n"15752026/09/22 08:50:43 WARN Failed to register uploaded object key=hgc72zzfwbnrp5qyrjkvxrlgfcnr381q.narinfo error="server returned 404: 404 page not found\n"15762026/09/22 08:50:43 WARN Failed to register uploaded object key=1in3q9rzaw1jk4zdnh6k2b187ahm9zac.narinfo error="server returned 404: 404 page not found\n"15772026-09-22 08:50:43.697 UTC [74235] ERROR: relation "goose_db_version" does not exist at character 3615782026-09-22 08:50:43.697 UTC [74235] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC15792026/09/22 08:50:43 WARN Failed to register uploaded object key=zgj1hf1lfv9yabwsnp68rnq7bryf6d9b.narinfo error="server returned 404: 404 page not found\n"15802026/09/22 08:50:43 WARN Failed to register uploaded object key=aq7lgbmcm7v5zsm2p4lyr2ngwplxxy78.narinfo error="server returned 404: 404 page not found\n"15812026/09/22 08:50:43 WARN Failed to register uploaded object key=g6b5gbjwqp56d72wsxz7a4pjrsdhab1k.narinfo error="server returned 404: 404 page not found\n"15822026/09/22 08:50:43 WARN Failed to register uploaded object key=nfcmif2wryzzgp3h24909w1lp42c25i1.narinfo error="server returned 404: 404 page not found\n"15832026/09/22 08:50:43 WARN Failed to register uploaded object key=ckr723m6sv93x0q7b9qvfdjds4v3b401.narinfo error="server returned 404: 404 page not found\n"15842026/09/22 08:50:43 WARN Failed to register uploaded object key=zrkd5lwn2c1a630ypjif3ijggd4npd5a.narinfo error="server returned 404: 404 page not found\n"1585--- PASS: TestService_Rustfstest (2.80s)1586=== CONT TestCreatePendingClosureRejectsOversizedNAR15872026/09/22 08:50:43 INFO Received uploads request method=POST path=/api/pending_closures1588--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)1589=== CONT TestCacheConfigHandlerMaxNarSize1590--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)1591=== CONT TestGenerateLandingPage1592--- PASS: TestGenerateLandingPage (0.00s)1593=== CONT TestService_readinessHandler15942026/09/22 08:50:43 WARN Failed to register uploaded object key=4ic5f7a8ynsalz74879ldncvd0cwkdf7.narinfo error="server returned 404: 404 page not found\n"15952026/09/22 08:50:43 WARN Failed to register uploaded object key=7sxnsmmrypnlv3pq78xc9ihqbq4fgrqs.narinfo error="server returned 404: 404 page not found\n"15962026/09/22 08:50:43 WARN Failed to register uploaded object key=6c7nrda2k1s1qq8c97rckhm3iy61hkz9.narinfo error="server returned 404: 404 page not found\n"15972026/09/22 08:50:43 OK 20241026095416_initial_model.sql (158.16ms)15982026/09/22 08:50:43 WARN Failed to register uploaded object key=d60c1h8784x4yd63g18jgh6jg60pppxg.narinfo error="server returned 404: 404 page not found\n"15992026/09/22 08:50:43 WARN Failed to register uploaded object key=msikdwgdb7qnfbc4izcr4v7fznqvfgfl.narinfo error="server returned 404: 404 page not found\n"16002026/09/22 08:50:43 WARN Failed to register uploaded object key=x4whajqp1nxcza087sr10696mwhk65kx.narinfo error="server returned 404: 404 page not found\n"16012026/09/22 08:50:43 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16022026/09/22 08:50:43 WARN Failed to register uploaded object key=njlvbnsaj76jsqg45w7v4540riqlcpx0.narinfo error="server returned 404: 404 page not found\n"16032026/09/22 08:50:43 OK 20251210153512_drop_unused_gin_index.sql (7.6ms)16042026/09/22 08:50:43 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16052026/09/22 08:50:43 OK 20251218171726_add_pins.sql (16.51ms)16062026/09/22 08:50:43 INFO Completed upload id=116072026/09/22 08:50:43 INFO Upload complete. (1.013s)1608--- PASS: TestClientReportsSignatures (4.29s)1609=== CONT TestService_healthCheckHandler16102026/09/22 08:50:43 OK 20260628120000_add_object_size_and_stats.sql (21.23ms)16112026/09/22 08:50:43 OK 20260905000000_add_claims.sql (20.85ms)16122026/09/22 08:50:43 OK 20260920000000_drop_claims.sql (16.31ms)16132026/09/22 08:50:43 goose: successfully migrated database to version: 2026092000000016142026/09/22 08:50:43 OK 1_commit_pending_closure.sql (2.39ms)16152026/09/22 08:50:43 OK 20241026095416_initial_model.sql (76.62ms)16162026/09/22 08:50:43 OK 2_object_stats_trigger.sql (507.58µs)16172026/09/22 08:50:43 goose: up to current file version: 216182026/09/22 08:50:43 OK 20251210153512_drop_unused_gin_index.sql (6.28ms)16192026/09/22 08:50:43 OK 20251218171726_add_pins.sql (30.44ms)16202026/09/22 08:50:43 OK 20260628120000_add_object_size_and_stats.sql (15.54ms)16212026/09/22 08:50:43 OK 20260905000000_add_claims.sql (52.39ms)16222026/09/22 08:50:43 OK 20260920000000_drop_claims.sql (18.17ms)16232026/09/22 08:50:43 goose: successfully migrated database to version: 2026092000000016242026/09/22 08:50:43 OK 1_commit_pending_closure.sql (3.66ms)16252026/09/22 08:50:43 OK 2_object_stats_trigger.sql (735.04µs)16262026/09/22 08:50:43 goose: up to current file version: 216272026-09-22 08:50:44.135 UTC [74243] ERROR: relation "goose_db_version" does not exist at character 3616282026-09-22 08:50:44.135 UTC [74243] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC16292026/09/22 08:50:44 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01630=== NAME TestPinProtectsFromGC1631 client_integration_test.go:795: Pin successfully protected closure from garbage collection1632--- PASS: TestPinProtectsFromGC (5.94s)1633=== CONT TestClientMultipleUploads16342026/09/22 08:50:44 OK 20241026095416_initial_model.sql (153.37ms)16352026/09/22 08:50:44 OK 20251210153512_drop_unused_gin_index.sql (8.2ms)16362026/09/22 08:50:44 OK 20251218171726_add_pins.sql (11.71ms)16372026-09-22 08:50:44.380 UTC [74250] ERROR: relation "goose_db_version" does not exist at character 3616382026-09-22 08:50:44.380 UTC [74250] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC16392026/09/22 08:50:44 OK 20260628120000_add_object_size_and_stats.sql (12.9ms)16402026/09/22 08:50:44 OK 20260905000000_add_claims.sql (39.13ms)16412026/09/22 08:50:44 OK 20260920000000_drop_claims.sql (22.3ms)16422026/09/22 08:50:44 goose: successfully migrated database to version: 2026092000000016432026/09/22 08:50:44 OK 1_commit_pending_closure.sql (931.88µs)16442026/09/22 08:50:44 OK 2_object_stats_trigger.sql (218.5µs)16452026/09/22 08:50:44 goose: up to current file version: 21646=== NAME TestOrphanedObjectsGCStressTest1647 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1648 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1649=== NAME TestClientWithDependencies1650 client_integration_test.go:614: Built derivation: /nix/var/nix/builds/nix-73934-291241723/TestClientWithDependencies2622099494/001/store/pxh209igh9d0wdynj8f93kq8h8mcxgyp-test-script1651 client_integration_test.go:616: Found 1 dependencies (including self)16522026/09/22 08:50:44 OK 20241026095416_initial_model.sql (222.58ms)16532026/09/22 08:50:44 OK 20251210153512_drop_unused_gin_index.sql (14.27ms)16542026/09/22 08:50:44 OK 20251218171726_add_pins.sql (21.25ms)16552026/09/22 08:50:44 OK 20260628120000_add_object_size_and_stats.sql (30.91ms)16562026/09/22 08:50:44 WARN mTLS auth: subject not in bound subjects subject="CN=reader"16572026/09/22 08:50:44 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1658--- PASS: TestService_NativeMTLS (2.29s)1659=== CONT TestGCTaskStore_GetEmpty1660--- PASS: TestGCTaskStore_GetEmpty (0.00s)1661=== CONT TestGCTaskStore_Fail1662--- PASS: TestGCTaskStore_Fail (0.00s)1663=== CONT TestGCTaskStore_PhaseUpdates1664--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)1665=== CONT TestGCTaskStore_CompletedAllowsNewTask1666--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)1667=== CONT TestGCTaskStore_GetReturnsLatest1668--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)1669=== CONT TestGCTaskStore_DeduplicateSameParams1670--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1671=== CONT TestGCTaskStore_ConflictDifferentParams1672--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1673=== CONT TestService_RequireScope_OIDC16742026/09/22 08:50:44 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"16752026/09/22 08:50:44 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"16762026/09/22 08:50:44 INFO Received uploads request method=POST path=/api/pending_closures16772026/09/22 08:50:44 OK 20260905000000_add_claims.sql (39.46ms)16782026/09/22 08:50:44 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16792026/09/22 08:50:44 INFO Uploading pxh209igh9d0wdynj8f93kq8h8mcxgyp-test-script (136B)16802026/09/22 08:50:44 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"16812026/09/22 08:50:44 OK 20260920000000_drop_claims.sql (20.26ms)16822026/09/22 08:50:44 goose: successfully migrated database to version: 2026092000000016832026/09/22 08:50:44 WARN Failed to register uploaded object key=log/x23vv9bc21c7lpc4d5r4jssdsxjn3l7m-test-script.drv error="server returned 404: 404 page not found\n"16842026/09/22 08:50:44 OK 1_commit_pending_closure.sql (1.32ms)16852026/09/22 08:50:44 OK 2_object_stats_trigger.sql (243.21µs)16862026/09/22 08:50:44 goose: up to current file version: 216872026/09/22 08:50:44 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign16882026/09/22 08:50:44 WARN Failed to register uploaded object key=pxh209igh9d0wdynj8f93kq8h8mcxgyp.ls error="server returned 404: 404 page not found\n"16892026/09/22 08:50:44 INFO Signed narinfos id=1 count=116902026/09/22 08:50:44 INFO Uploading 1 narinfos16912026/09/22 08:50:44 INFO Received uploads request method=POST path=/api/pending_closures16922026/09/22 08:50:44 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16932026/09/22 08:50:44 WARN Failed to register uploaded object key=pxh209igh9d0wdynj8f93kq8h8mcxgyp.narinfo error="server returned 404: 404 page not found\n"16942026-09-22 08:50:44.814 UTC [74268] ERROR: relation "goose_db_version" does not exist at character 3616952026-09-22 08:50:44.814 UTC [74268] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC16962026/09/22 08:50:44 INFO Completed upload id=116972026/09/22 08:50:44 INFO Upload complete. (148ms)1698=== NAME TestClientWithDependencies1699 client_integration_test.go:618: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-73934-291241723/TestClientWithDependencies2622099494/001/store) requires matching store prefix17002026/09/22 08:50:44 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53168/oidc17012026/09/22 08:50:44 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"1702--- PASS: TestClientWithDependencies (3.20s)1703=== CONT TestClientCADerivations17042026/09/22 08:50:44 INFO Received uploads request method=POST path=/api/pending_closures17052026/09/22 08:50:44 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)17062026/09/22 08:50:44 INFO Uploading aw15j0dv6k8km8siv9ch278i3qkwzf2k-shared-dep (136B)17072026/09/22 08:50:44 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"17082026/09/22 08:50:44 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign17092026/09/22 08:50:44 INFO Signed narinfos id=2 count=117102026/09/22 08:50:44 WARN Failed to register uploaded object key=aw15j0dv6k8km8siv9ch278i3qkwzf2k.ls error="server returned 404: 404 page not found\n"17112026/09/22 08:50:44 INFO Uploading 1 narinfos17122026/09/22 08:50:44 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete17132026/09/22 08:50:44 WARN Failed to register uploaded object key=aw15j0dv6k8km8siv9ch278i3qkwzf2k.narinfo error="server returned 404: 404 page not found\n"17142026/09/22 08:50:45 INFO Completed upload id=217152026/09/22 08:50:45 INFO Upload complete. (161ms)17162026/09/22 08:50:45 INFO Received uploads request method=POST path=/api/pending_closures17172026/09/22 08:50:45 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)17182026/09/22 08:50:45 INFO Uploading n4zip80s4885shrqy0k8cgrafzvqqcv3-top (256B)17192026/09/22 08:50:45 INFO Uploading aw15j0dv6k8km8siv9ch278i3qkwzf2k-shared-dep (136B)17202026/09/22 08:50:45 OK 20241026095416_initial_model.sql (166.6ms)17212026/09/22 08:50:45 WARN Failed to register uploaded object key=nar/1xgdja0aa16wqxsr6p7ghl51mngxb3d74dn6v0vzf4hl3sxggh3i.nar.zst error="server returned 404: 404 page not found\n"17222026/09/22 08:50:45 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"17232026/09/22 08:50:45 OK 20251210153512_drop_unused_gin_index.sql (14.21ms)17242026/09/22 08:50:45 WARN Failed to register uploaded object key=n4zip80s4885shrqy0k8cgrafzvqqcv3.ls error="server returned 404: 404 page not found\n"17252026/09/22 08:50:45 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign17262026/09/22 08:50:45 INFO Signed narinfos id=1 count=117272026/09/22 08:50:45 WARN Failed to register uploaded object key=aw15j0dv6k8km8siv9ch278i3qkwzf2k.ls error="server returned 404: 404 page not found\n"17282026/09/22 08:50:45 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign17292026/09/22 08:50:45 INFO Signed narinfos id=3 count=117302026/09/22 08:50:45 INFO Uploading 2 narinfos17312026/09/22 08:50:45 OK 20251218171726_add_pins.sql (35.74ms)1732--- PASS: TestMetricsInventory (2.42s)1733=== CONT TestCacheStatsHandler17342026/09/22 08:50:45 WARN Failed to register uploaded object key=n4zip80s4885shrqy0k8cgrafzvqqcv3.narinfo error="server returned 404: 404 page not found\n"17352026/09/22 08:50:45 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete17362026/09/22 08:50:45 WARN Failed to register uploaded object key=aw15j0dv6k8km8siv9ch278i3qkwzf2k.narinfo error="server returned 404: 404 page not found\n"17372026/09/22 08:50:45 INFO Completed upload id=117382026/09/22 08:50:45 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete17392026/09/22 08:50:45 INFO Completed upload id=317402026/09/22 08:50:45 INFO Upload complete. (425ms)1741=== NAME TestClientSharedPathCommittedMidPush1742 client_integration_test.go:681: Retrieved narinfo from S3:1743 StorePath: /nix/var/nix/builds/nix-73934-291241723/TestClientSharedPathCommittedMidPush2383657266/001/store/aw15j0dv6k8km8siv9ch278i3qkwzf2k-shared-dep1744 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1745 Compression: zstd1746 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821747 NarSize: 1361748 References: 1749 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1750 client_integration_test.go:681: Retrieved narinfo from S3:1751 StorePath: /nix/var/nix/builds/nix-73934-291241723/TestClientSharedPathCommittedMidPush2383657266/001/store/n4zip80s4885shrqy0k8cgrafzvqqcv3-top1752 URL: nar/1xgdja0aa16wqxsr6p7ghl51mngxb3d74dn6v0vzf4hl3sxggh3i.nar.zst1753 Compression: zstd1754 NarHash: sha256:1xgdja0aa16wqxsr6p7ghl51mngxb3d74dn6v0vzf4hl3sxggh3i1755 NarSize: 2561756 References: /nix/var/nix/builds/nix-73934-291241723/TestClientSharedPathCommittedMidPush2383657266/001/store/aw15j0dv6k8km8siv9ch278i3qkwzf2k-shared-dep1757 CA: text:sha256:0kah1pj8gzqgs2zhhnqmk61v4h9r5gdjix1wwhfmf3h96gjp0jal17582026/09/22 08:50:45 OK 20260628120000_add_object_size_and_stats.sql (41.51ms)17592026/09/22 08:50:45 OK 20260905000000_add_claims.sql (59.97ms)1760--- PASS: TestClientSharedPathCommittedMidPush (3.26s)1761=== CONT TestCacheConfigHandler1762=== RUN TestCacheConfigHandler/full_config,_no_issuer1763=== PAUSE TestCacheConfigHandler/full_config,_no_issuer1764=== RUN TestCacheConfigHandler/no_cache_url_configured1765=== PAUSE TestCacheConfigHandler/no_cache_url_configured1766=== RUN TestCacheConfigHandler/no_signing_keys1767=== PAUSE TestCacheConfigHandler/no_signing_keys1768=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1769=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1770=== CONT TestService_ReadScope_PublicByDefault17712026/09/22 08:50:45 OK 20260920000000_drop_claims.sql (28.69ms)17722026/09/22 08:50:45 goose: successfully migrated database to version: 2026092000000017732026/09/22 08:50:45 OK 1_commit_pending_closure.sql (1.59ms)17742026/09/22 08:50:45 OK 2_object_stats_trigger.sql (322.58µs)17752026/09/22 08:50:45 goose: up to current file version: 217762026-09-22 08:50:45.341 UTC [74283] ERROR: relation "goose_db_version" does not exist at character 3617772026-09-22 08:50:45.341 UTC [74283] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC17782026-09-22 08:50:45.348 UTC [74284] ERROR: relation "goose_db_version" does not exist at character 3617792026-09-22 08:50:45.348 UTC [74284] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC17802026/09/22 08:50:45 OK 20241026095416_initial_model.sql (120.1ms)17812026/09/22 08:50:45 OK 20251210153512_drop_unused_gin_index.sql (6.81ms)17822026/09/22 08:50:45 OK 20251218171726_add_pins.sql (19.95ms)17832026/09/22 08:50:45 OK 20241026095416_initial_model.sql (144.3ms)17842026/09/22 08:50:45 OK 20251210153512_drop_unused_gin_index.sql (1.71ms)17852026/09/22 08:50:45 OK 20260628120000_add_object_size_and_stats.sql (15.54ms)17862026/09/22 08:50:45 OK 20251218171726_add_pins.sql (14.23ms)17872026/09/22 08:50:45 OK 20260905000000_add_claims.sql (21.35ms)17882026/09/22 08:50:45 OK 20260920000000_drop_claims.sql (1.44ms)17892026/09/22 08:50:45 goose: successfully migrated database to version: 2026092000000017902026/09/22 08:50:45 OK 1_commit_pending_closure.sql (1.1ms)17912026/09/22 08:50:45 OK 2_object_stats_trigger.sql (245.38µs)17922026/09/22 08:50:45 goose: up to current file version: 217932026/09/22 08:50:45 OK 20260628120000_add_object_size_and_stats.sql (29.65ms)17942026/09/22 08:50:45 OK 20260905000000_add_claims.sql (48.74ms)1795=== NAME TestNARDeduplicationMetadataUploadBug1796 metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-73934-291241723/TestNARDeduplicationMetadataUploadBug1271232322/001/store/qj58xf5g7rc64s7a4506a429ap05djiy-file1.txt17972026/09/22 08:50:45 OK 20260920000000_drop_claims.sql (19.56ms)17982026/09/22 08:50:45 goose: successfully migrated database to version: 2026092000000017992026/09/22 08:50:45 OK 1_commit_pending_closure.sql (1.4ms)18002026/09/22 08:50:45 OK 2_object_stats_trigger.sql (275.38µs)18012026/09/22 08:50:45 goose: up to current file version: 218022026/09/22 08:50:45 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"18032026/09/22 08:50:45 INFO Received uploads request method=POST path=/api/pending_closures1804--- PASS: TestService_healthCheckHandler (2.03s)1805=== CONT TestGCTaskStore_StartNew1806--- PASS: TestGCTaskStore_StartNew (0.00s)1807=== CONT TestClientIntegration18082026/09/22 08:50:45 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)18092026/09/22 08:50:45 INFO Uploading qj58xf5g7rc64s7a4506a429ap05djiy-file1.txt (160B)18102026/09/22 08:50:45 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"18112026/09/22 08:50:45 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign18122026/09/22 08:50:45 WARN Failed to register uploaded object key=qj58xf5g7rc64s7a4506a429ap05djiy.ls error="server returned 404: 404 page not found\n"18132026/09/22 08:50:45 INFO Signed narinfos id=1 count=118142026/09/22 08:50:45 INFO Uploading 1 narinfos18152026/09/22 08:50:45 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete18162026/09/22 08:50:45 WARN Failed to register uploaded object key=qj58xf5g7rc64s7a4506a429ap05djiy.narinfo error="server returned 404: 404 page not found\n"18172026/09/22 08:50:45 INFO Completed upload id=118182026/09/22 08:50:45 INFO Upload complete. (277ms)1819=== NAME TestNARDeduplicationMetadataUploadBug1820 metadata_upload_test.go:54: Retrieved narinfo from S3:1821 StorePath: /nix/var/nix/builds/nix-73934-291241723/TestNARDeduplicationMetadataUploadBug1271232322/001/store/qj58xf5g7rc64s7a4506a429ap05djiy-file1.txt1822 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1823 Compression: zstd1824 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1825 NarSize: 1601826 References: 1827 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1828 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1829 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1830 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}18312026-09-22 08:50:45.959 UTC [74295] ERROR: relation "goose_db_version" does not exist at character 3618322026-09-22 08:50:45.959 UTC [74295] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1833 metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-73934-291241723/TestNARDeduplicationMetadataUploadBug1271232322/001/store/7pjnrlygpkrk91rqgr2dqzjg7cnk14a1-file2.txt18342026/09/22 08:50:46 WARN readiness check failed error="closed pool"1835--- PASS: TestService_readinessHandler (2.38s)1836=== CONT TestCompleteMultipartUpload_ErrorButObjectExists18372026/09/22 08:50:46 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"18382026/09/22 08:50:46 OK 20241026095416_initial_model.sql (130.42ms)18392026/09/22 08:50:46 OK 20251210153512_drop_unused_gin_index.sql (5.7ms)18402026/09/22 08:50:46 OK 20251218171726_add_pins.sql (1.81ms)18412026/09/22 08:50:46 OK 20260628120000_add_object_size_and_stats.sql (5.39ms)18422026-09-22 08:50:46.174 UTC [74305] ERROR: relation "goose_db_version" does not exist at character 3618432026-09-22 08:50:46.174 UTC [74305] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC18442026/09/22 08:50:46 OK 20260905000000_add_claims.sql (2.16ms)18452026/09/22 08:50:46 OK 20260920000000_drop_claims.sql (773.71µs)18462026/09/22 08:50:46 goose: successfully migrated database to version: 2026092000000018472026/09/22 08:50:46 OK 1_commit_pending_closure.sql (932.63µs)18482026/09/22 08:50:46 OK 2_object_stats_trigger.sql (333.71µs)18492026/09/22 08:50:46 goose: up to current file version: 218502026/09/22 08:50:46 INFO Received uploads request method=POST path=/api/pending_closures18512026/09/22 08:50:46 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)18522026/09/22 08:50:46 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign18532026/09/22 08:50:46 INFO Signed narinfos id=2 count=118542026/09/22 08:50:46 WARN Failed to register uploaded object key=7pjnrlygpkrk91rqgr2dqzjg7cnk14a1.ls error="server returned 404: 404 page not found\n"18552026/09/22 08:50:46 INFO Uploading 1 narinfos18562026-09-22 08:50:46.228 UTC [74307] ERROR: relation "goose_db_version" does not exist at character 3618572026-09-22 08:50:46.228 UTC [74307] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC18582026/09/22 08:50:46 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete18592026/09/22 08:50:46 WARN Failed to register uploaded object key=7pjnrlygpkrk91rqgr2dqzjg7cnk14a1.narinfo error="server returned 404: 404 page not found\n"18602026/09/22 08:50:46 INFO Completed upload id=218612026/09/22 08:50:46 INFO Upload complete. (124ms)1862=== NAME TestNARDeduplicationMetadataUploadBug1863 metadata_upload_test.go:76: Retrieved narinfo from S3:1864 StorePath: /nix/var/nix/builds/nix-73934-291241723/TestNARDeduplicationMetadataUploadBug1271232322/001/store/7pjnrlygpkrk91rqgr2dqzjg7cnk14a1-file2.txt1865 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1866 Compression: zstd1867 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1868 NarSize: 1601869 References: 1870 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1871 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1872 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1873 {"version":1,"root":{"type":"regular","size":44}}1874--- PASS: TestNARDeduplicationMetadataUploadBug (3.27s)1875=== CONT TestCompletedNarNotReofferedAcrossClosures18762026/09/22 08:50:46 OK 20241026095416_initial_model.sql (125.32ms)18772026/09/22 08:50:46 OK 20251210153512_drop_unused_gin_index.sql (1.29ms)18782026/09/22 08:50:46 OK 20251218171726_add_pins.sql (13.73ms)18792026/09/22 08:50:46 OK 20241026095416_initial_model.sql (82.36ms)18802026/09/22 08:50:46 OK 20251210153512_drop_unused_gin_index.sql (6.91ms)18812026/09/22 08:50:46 OK 20260628120000_add_object_size_and_stats.sql (14.82ms)18822026/09/22 08:50:46 OK 20251218171726_add_pins.sql (18.98ms)18832026/09/22 08:50:46 OK 20260905000000_add_claims.sql (27.66ms)18842026/09/22 08:50:46 OK 20260920000000_drop_claims.sql (14.37ms)18852026/09/22 08:50:46 goose: successfully migrated database to version: 2026092000000018862026/09/22 08:50:46 OK 20260628120000_add_object_size_and_stats.sql (23.24ms)18872026/09/22 08:50:46 OK 1_commit_pending_closure.sql (1.66ms)18882026/09/22 08:50:46 OK 2_object_stats_trigger.sql (302.17µs)18892026/09/22 08:50:46 goose: up to current file version: 218902026/09/22 08:50:46 OK 20260905000000_add_claims.sql (25.61ms)18912026/09/22 08:50:46 OK 20260920000000_drop_claims.sql (22.59ms)18922026/09/22 08:50:46 goose: successfully migrated database to version: 2026092000000018932026/09/22 08:50:46 OK 1_commit_pending_closure.sql (3.48ms)18942026/09/22 08:50:46 OK 2_object_stats_trigger.sql (353.5µs)18952026/09/22 08:50:46 goose: up to current file version: 218962026-09-22 08:50:46.500 UTC [74311] ERROR: relation "goose_db_version" does not exist at character 3618972026-09-22 08:50:46.500 UTC [74311] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1898=== NAME TestClientMultipleUploads1899 client_integration_test.go:359: Created store path 0: /nix/var/nix/builds/nix-73934-291241723/TestClientMultipleUploads1867790151/001/store/my1cc4fjcx6rjgka2wzdz0in56v3kgvl-test-file-0.txt19002026-09-22 08:50:46.594 UTC [74314] ERROR: relation "goose_db_version" does not exist at character 3619012026-09-22 08:50:46.594 UTC [74314] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1902=== RUN TestService_RequireScope_OIDC/builder_may_write1903=== PAUSE TestService_RequireScope_OIDC/builder_may_write1904=== RUN TestService_RequireScope_OIDC/builder_may_not_admin1905=== PAUSE TestService_RequireScope_OIDC/builder_may_not_admin1906=== RUN TestService_RequireScope_OIDC/ops_may_admin1907=== PAUSE TestService_RequireScope_OIDC/ops_may_admin1908=== RUN TestService_RequireScope_OIDC/ops_may_not_write1909=== PAUSE TestService_RequireScope_OIDC/ops_may_not_write1910=== RUN TestService_RequireScope_OIDC/reader_may_not_write1911=== PAUSE TestService_RequireScope_OIDC/reader_may_not_write1912=== RUN TestService_RequireScope_OIDC/static_token_may_admin1913=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin1914=== RUN TestService_RequireScope_OIDC/static_token_may_write1915=== PAUSE TestService_RequireScope_OIDC/static_token_may_write1916=== RUN TestService_RequireScope_OIDC/reader_may_read1917=== PAUSE TestService_RequireScope_OIDC/reader_may_read1918=== RUN TestService_RequireScope_OIDC/writer_implies_read1919=== PAUSE TestService_RequireScope_OIDC/writer_implies_read1920=== RUN TestService_RequireScope_OIDC/anonymous_may_not_read1921=== PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read1922=== CONT TestServerTLSConfig/no_client_CA1923=== CONT TestRedundantMultipartUpload19242026/09/22 08:50:46 OK 20241026095416_initial_model.sql (91.14ms)1925=== NAME TestClientMultipleUploads1926 client_integration_test.go:359: Created store path 1: /nix/var/nix/builds/nix-73934-291241723/TestClientMultipleUploads1867790151/001/store/jb61xy9zjmd4m2wbyglp3l5nkqwabg5l-test-file-1.txt19272026/09/22 08:50:46 OK 20251210153512_drop_unused_gin_index.sql (2.18ms)19282026/09/22 08:50:46 OK 20251218171726_add_pins.sql (43.73ms)1929 client_integration_test.go:359: Created store path 2: /nix/var/nix/builds/nix-73934-291241723/TestClientMultipleUploads1867790151/001/store/aqvqqdihw8bx6x2sz4s0x2fg7q2yazif-test-file-2.txt19302026/09/22 08:50:46 OK 20260628120000_add_object_size_and_stats.sql (13ms)1931=== NAME TestOrphanedObjectsGCStressTest1932 orphaned_objects_gc_test.go:509: Stress test completed successfully:1933 orphaned_objects_gc_test.go:510: - Active objects preserved: 201934 orphaned_objects_gc_test.go:511: - Objects deleted: 2101935 orphaned_objects_gc_test.go:512: - Total GC'd: 2101936--- PASS: TestOrphanedObjectsGCStressTest (10.07s)1937=== CONT TestReadRedirectUsesPublicS3URL19382026/09/22 08:50:46 OK 20241026095416_initial_model.sql (90.37ms)19392026/09/22 08:50:46 OK 20251210153512_drop_unused_gin_index.sql (9.88ms)19402026/09/22 08:50:46 OK 20260905000000_add_claims.sql (32.73ms)19412026/09/22 08:50:46 OK 20251218171726_add_pins.sql (28ms)19422026/09/22 08:50:46 OK 20260920000000_drop_claims.sql (34.5ms)19432026/09/22 08:50:46 goose: successfully migrated database to version: 2026092000000019442026/09/22 08:50:46 OK 1_commit_pending_closure.sql (881.79µs)19452026/09/22 08:50:46 OK 2_object_stats_trigger.sql (236.21µs)19462026/09/22 08:50:46 goose: up to current file version: 219472026/09/22 08:50:46 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"19482026/09/22 08:50:46 OK 20260628120000_add_object_size_and_stats.sql (18.58ms)19492026/09/22 08:50:46 OK 20260905000000_add_claims.sql (16.06ms)19502026/09/22 08:50:46 OK 20260920000000_drop_claims.sql (21.69ms)19512026/09/22 08:50:46 goose: successfully migrated database to version: 2026092000000019522026/09/22 08:50:46 OK 1_commit_pending_closure.sql (1.38ms)19532026/09/22 08:50:46 OK 2_object_stats_trigger.sql (270.08µs)19542026/09/22 08:50:46 goose: up to current file version: 219552026/09/22 08:50:46 INFO Received uploads request method=POST path=/api/pending_closures19562026/09/22 08:50:46 INFO Received uploads request method=POST path=/api/pending_closures19572026/09/22 08:50:46 INFO Received uploads request method=POST path=/api/pending_closures19582026/09/22 08:50:46 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)19592026/09/22 08:50:46 INFO Uploading my1cc4fjcx6rjgka2wzdz0in56v3kgvl-test-file-0.txt (160B)19602026/09/22 08:50:46 INFO Uploading aqvqqdihw8bx6x2sz4s0x2fg7q2yazif-test-file-2.txt (160B)19612026/09/22 08:50:46 INFO Uploading jb61xy9zjmd4m2wbyglp3l5nkqwabg5l-test-file-1.txt (160B)19622026/09/22 08:50:46 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"19632026/09/22 08:50:46 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"19642026/09/22 08:50:46 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"19652026/09/22 08:50:46 WARN Failed to register uploaded object key=my1cc4fjcx6rjgka2wzdz0in56v3kgvl.ls error="server returned 404: 404 page not found\n"19662026/09/22 08:50:46 WARN Failed to register uploaded object key=jb61xy9zjmd4m2wbyglp3l5nkqwabg5l.ls error="server returned 404: 404 page not found\n"19672026/09/22 08:50:46 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign19682026/09/22 08:50:46 WARN Failed to register uploaded object key=aqvqqdihw8bx6x2sz4s0x2fg7q2yazif.ls error="server returned 404: 404 page not found\n"19692026/09/22 08:50:46 INFO Signed narinfos id=2 count=119702026/09/22 08:50:46 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign19712026/09/22 08:50:46 INFO Signed narinfos id=3 count=119722026/09/22 08:50:46 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign19732026/09/22 08:50:46 INFO Signed narinfos id=1 count=119742026/09/22 08:50:46 INFO Uploading 3 narinfos19752026/09/22 08:50:46 WARN Failed to register uploaded object key=aqvqqdihw8bx6x2sz4s0x2fg7q2yazif.narinfo error="server returned 404: 404 page not found\n"19762026/09/22 08:50:46 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete19772026/09/22 08:50:46 WARN Failed to register uploaded object key=my1cc4fjcx6rjgka2wzdz0in56v3kgvl.narinfo error="server returned 404: 404 page not found\n"19782026/09/22 08:50:46 WARN Failed to register uploaded object key=jb61xy9zjmd4m2wbyglp3l5nkqwabg5l.narinfo error="server returned 404: 404 page not found\n"19792026/09/22 08:50:46 INFO Completed upload id=319802026/09/22 08:50:46 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete19812026/09/22 08:50:46 INFO Completed upload id=119822026/09/22 08:50:46 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete19832026/09/22 08:50:46 INFO Completed upload id=219842026/09/22 08:50:46 INFO Upload complete. (164ms)1985=== NAME TestClientMultipleUploads1986 client_integration_test.go:370: Uploaded 3 paths in 200.787416ms1987--- PASS: TestClientMultipleUploads (2.60s)1988=== CONT TestGCMetrics1989--- PASS: TestCacheStatsHandler (2.00s)1990=== CONT TestService_ReadAuthMiddleware19912026-09-22 08:50:47.115 UTC [74336] ERROR: relation "goose_db_version" does not exist at character 3619922026-09-22 08:50:47.115 UTC [74336] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC19932026-09-22 08:50:47.157 UTC [74338] ERROR: relation "goose_db_version" does not exist at character 3619942026-09-22 08:50:47.157 UTC [74338] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1995=== NAME TestClientCADerivations1996 client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-73934-291241723/TestClientCADerivations758784441/001/store/njlpwr32hdw6l72iy98vzl28ypbbgzfh-ca-test19972026/09/22 08:50:47 OK 20241026095416_initial_model.sql (76.88ms)1998--- PASS: TestService_ReadScope_PublicByDefault (2.03s)1999=== CONT TestService_AuthMiddleware_OIDC2000=== NAME TestClientCADerivations2001 client_ca_test.go:139: Found 1 dependencies (including self)20022026/09/22 08:50:47 OK 20251210153512_drop_unused_gin_index.sql (9.31ms)20032026/09/22 08:50:47 OK 20251218171726_add_pins.sql (11.42ms)20042026/09/22 08:50:47 OK 20260628120000_add_object_size_and_stats.sql (12.87ms)20052026/09/22 08:50:47 OK 20241026095416_initial_model.sql (63.49ms)20062026/09/22 08:50:47 OK 20251210153512_drop_unused_gin_index.sql (389.33µs)20072026/09/22 08:50:47 OK 20251218171726_add_pins.sql (751.83µs)20082026/09/22 08:50:47 OK 20260905000000_add_claims.sql (5.92ms)20092026/09/22 08:50:47 OK 20260628120000_add_object_size_and_stats.sql (5.13ms)20102026/09/22 08:50:47 OK 20260920000000_drop_claims.sql (1.35ms)20112026/09/22 08:50:47 goose: successfully migrated database to version: 2026092000000020122026/09/22 08:50:47 OK 1_commit_pending_closure.sql (1.2ms)20132026/09/22 08:50:47 OK 2_object_stats_trigger.sql (347µs)20142026/09/22 08:50:47 goose: up to current file version: 220152026-09-22 08:50:47.263 UTC [74343] ERROR: relation "goose_db_version" does not exist at character 3620162026-09-22 08:50:47.263 UTC [74343] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC20172026/09/22 08:50:47 OK 20260905000000_add_claims.sql (2.55ms)20182026/09/22 08:50:47 OK 20260920000000_drop_claims.sql (758.92µs)20192026/09/22 08:50:47 goose: successfully migrated database to version: 2026092000000020202026/09/22 08:50:47 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53200/oidc20212026/09/22 08:50:47 OK 1_commit_pending_closure.sql (1.7ms)20222026/09/22 08:50:47 OK 2_object_stats_trigger.sql (253.42µs)20232026/09/22 08:50:47 goose: up to current file version: 220242026/09/22 08:50:47 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"20252026/09/22 08:50:47 WARN Rate limiter enabled after throttle name=s3-test rate=520262026/09/22 08:50:47 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."2027=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle2028 throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=102029 throttle_test.go:215: Rate limiter: enabled=true, rate=5.002030--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (7.46s)2031=== CONT TestService_AuthMiddleware_MTLSBoundSubjects20322026/09/22 08:50:47 OK 20241026095416_initial_model.sql (69.73ms)20332026/09/22 08:50:47 OK 20251210153512_drop_unused_gin_index.sql (1.42ms)20342026/09/22 08:50:47 INFO Received uploads request method=POST path=/api/pending_closures20352026/09/22 08:50:47 OK 20251218171726_add_pins.sql (14.91ms)20362026/09/22 08:50:47 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)20372026/09/22 08:50:47 INFO Uploading njlpwr32hdw6l72iy98vzl28ypbbgzfh-ca-test (144B)20382026/09/22 08:50:47 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"20392026/09/22 08:50:47 OK 20260628120000_add_object_size_and_stats.sql (20.94ms)20402026/09/22 08:50:47 WARN Failed to register uploaded object key=log/2iwnj4y83gfa0zp9h9ihpp99vyca6552-ca-test.drv error="server returned 404: 404 page not found\n"20412026/09/22 08:50:47 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign20422026/09/22 08:50:47 INFO Signed narinfos id=1 count=120432026/09/22 08:50:47 WARN Failed to register uploaded object key=njlpwr32hdw6l72iy98vzl28ypbbgzfh.ls error="server returned 404: 404 page not found\n"20442026/09/22 08:50:47 INFO Uploading 1 narinfos20452026/09/22 08:50:47 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete20462026/09/22 08:50:47 WARN Failed to register uploaded object key=njlpwr32hdw6l72iy98vzl28ypbbgzfh.narinfo error="server returned 404: 404 page not found\n"20472026/09/22 08:50:47 OK 20260905000000_add_claims.sql (40.77ms)20482026/09/22 08:50:47 INFO Completed upload id=120492026/09/22 08:50:47 INFO Upload complete. (159ms)2050=== NAME TestClientCADerivations2051 client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-73934-291241723/TestClientCADerivations758784441/001/store/njlpwr32hdw6l72iy98vzl28ypbbgzfh-ca-test2052 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst2053 Compression: zstd2054 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n2055 NarSize: 1442056 References: 2057 Deriver: /nix/var/nix/builds/nix-73934-291241723/TestClientCADerivations758784441/001/store/2iwnj4y83gfa0zp9h9ihpp99vyca6552-ca-test.drv2058 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n2059 client_ca_test.go:185: Checking for realisation files in S3...2060 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations2061 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache20622026/09/22 08:50:47 OK 20260920000000_drop_claims.sql (14.16ms)20632026/09/22 08:50:47 goose: successfully migrated database to version: 2026092000000020642026/09/22 08:50:47 OK 1_commit_pending_closure.sql (933.17µs)20652026/09/22 08:50:47 OK 2_object_stats_trigger.sql (235.75µs)20662026/09/22 08:50:47 goose: up to current file version: 22067 client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket44?endpoint=http://localhost:53046®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-73934-291241723/TestClientCADerivations758784441/001/store'2068 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 12069--- PASS: TestClientCADerivations (2.61s)2070=== CONT TestService_AuthMiddleware_MTLSProxyHeader2071=== NAME TestClientIntegration2072 client_integration_test.go:287: Created store path: /nix/var/nix/builds/nix-73934-291241723/TestClientIntegration3067041146/002/store/4h9i8d3dvp92i583q3lxvqii7dnzf5pi-test-file.txt20732026-09-22 08:50:47.606 UTC [74359] ERROR: relation "goose_db_version" does not exist at character 3620742026-09-22 08:50:47.606 UTC [74359] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC20752026/09/22 08:50:47 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"20762026/09/22 08:50:47 INFO Received uploads request method=POST path=/api/pending_closures20772026/09/22 08:50:47 INFO Received uploads request method=POST path=/api/pending_closures20782026/09/22 08:50:47 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)20792026/09/22 08:50:47 INFO Uploading 4h9i8d3dvp92i583q3lxvqii7dnzf5pi-test-file.txt (152B)20802026-09-22 08:50:47.736 UTC [74365] ERROR: relation "goose_db_version" does not exist at character 3620812026-09-22 08:50:47.736 UTC [74365] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC20822026/09/22 08:50:47 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"20832026/09/22 08:50:47 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign20842026/09/22 08:50:47 INFO Signed narinfos id=1 count=120852026/09/22 08:50:47 WARN Failed to register uploaded object key=4h9i8d3dvp92i583q3lxvqii7dnzf5pi.ls error="server returned 404: 404 page not found\n"20862026/09/22 08:50:47 INFO Uploading 1 narinfos20872026/09/22 08:50:47 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete20882026/09/22 08:50:47 WARN Failed to register uploaded object key=4h9i8d3dvp92i583q3lxvqii7dnzf5pi.narinfo error="server returned 404: 404 page not found\n"20892026/09/22 08:50:47 OK 20241026095416_initial_model.sql (180.32ms)20902026/09/22 08:50:47 OK 20251210153512_drop_unused_gin_index.sql (4.89ms)20912026/09/22 08:50:47 INFO Completed upload id=120922026/09/22 08:50:47 INFO Upload complete. (245ms)20932026/09/22 08:50:47 INFO All 1 paths already cached2094 client_integration_test.go:313: Retrieved narinfo from S3:2095 StorePath: /nix/var/nix/builds/nix-73934-291241723/TestClientIntegration3067041146/002/store/4h9i8d3dvp92i583q3lxvqii7dnzf5pi-test-file.txt2096 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst2097 Compression: zstd2098 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk12099 NarSize: 1522100 References: 2101 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk121022026/09/22 08:50:47 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2103 client_integration_test.go:314: Retrieved .ls file from S3 (compressed size: 77 bytes)2104 client_integration_test.go:314: Decompressed .ls content (64 bytes):2105 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}2106 client_integration_test.go:317: Testing garbage collection...21072026/09/22 08:50:47 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjRiMjEzMTE5LTIwNGItNDk4Zi05ZDdjLTc2NDdlMzFkY2QwZngxNzkwMDY3MDQ3NjY5ODE1MDAw21082026/09/22 08:50:47 OK 20251218171726_add_pins.sql (58.31ms)21092026/09/22 08:50:47 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjRiMjEzMTE5LTIwNGItNDk4Zi05ZDdjLTc2NDdlMzFkY2QwZngxNzkwMDY3MDQ3NjY5ODE1MDAw parts=12110--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (1.77s)2111=== CONT TestServerTLSConfig/not_a_PEM_file2112=== CONT TestServerTLSConfig/missing_CA_file2113--- PASS: TestServerTLSConfig (0.00s)2114 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)2115 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.02s)2116 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)2117=== CONT TestClientErrorHandling/InvalidStorePath21182026/09/22 08:50:47 OK 20260628120000_add_object_size_and_stats.sql (29.84ms)21192026/09/22 08:50:47 INFO Starting cleanup of old closures method=DELETE path=/api/closures21202026/09/22 08:50:47 INFO Garbage collection started21212026/09/22 08:50:47 INFO Aborted multipart uploads count=021222026/09/22 08:50:47 WARN Force mode enabled - objects will be deleted immediately without grace period21232026/09/22 08:50:47 INFO Received uploads request method=POST path=/api/pending_closures21242026/09/22 08:50:47 OK 20260905000000_add_claims.sql (28.47ms)21252026/09/22 08:50:47 OK 20241026095416_initial_model.sql (143.72ms)21262026/09/22 08:50:47 OK 20260920000000_drop_claims.sql (22.57ms)21272026/09/22 08:50:47 goose: successfully migrated database to version: 2026092000000021282026/09/22 08:50:47 OK 1_commit_pending_closure.sql (953.33µs)21292026/09/22 08:50:47 OK 2_object_stats_trigger.sql (235.5µs)21302026/09/22 08:50:47 goose: up to current file version: 221312026/09/22 08:50:47 OK 20251210153512_drop_unused_gin_index.sql (7.4ms)21322026/09/22 08:50:47 OK 20251218171726_add_pins.sql (20.32ms)21332026/09/22 08:50:48 OK 20260628120000_add_object_size_and_stats.sql (81.55ms)21342026/09/22 08:50:48 OK 20260905000000_add_claims.sql (50.43ms)21352026/09/22 08:50:48 OK 20260920000000_drop_claims.sql (23.41ms)21362026/09/22 08:50:48 goose: successfully migrated database to version: 2026092000000021372026/09/22 08:50:48 OK 1_commit_pending_closure.sql (983.08µs)21382026/09/22 08:50:48 OK 2_object_stats_trigger.sql (225.38µs)21392026/09/22 08:50:48 goose: up to current file version: 221402026-09-22 08:50:48.212 UTC [74374] ERROR: relation "goose_db_version" does not exist at character 3621412026-09-22 08:50:48.212 UTC [74374] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC21422026/09/22 08:50:48 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3003 objects-failed-to-delete=021432026/09/22 08:50:48 INFO Received uploads request method=POST path=/api/pending_closures21442026/09/22 08:50:48 INFO Vacuumed table table=pending_closures21452026/09/22 08:50:48 INFO Vacuumed table table=pending_objects21462026/09/22 08:50:48 INFO Vacuumed table table=multipart_uploads21472026/09/22 08:50:48 INFO Received uploads request method=POST path=/api/pending_closures21482026/09/22 08:50:48 INFO Vacuumed table table=closures21492026/09/22 08:50:48 INFO Vacuumed table table=objects21502026/09/22 08:50:48 OK 20241026095416_initial_model.sql (239.18ms)21512026/09/22 08:50:48 OK 20251210153512_drop_unused_gin_index.sql (14.41ms)21522026/09/22 08:50:48 OK 20251218171726_add_pins.sql (28.95ms)21532026/09/22 08:50:48 OK 20260628120000_add_object_size_and_stats.sql (30.72ms)2154--- PASS: TestReadRedirectUsesPublicS3URL (1.89s)2155=== CONT TestClientErrorHandling/ServerNotAvailable21562026-09-22 08:50:48.665 UTC [74375] ERROR: relation "goose_db_version" does not exist at character 3621572026-09-22 08:50:48.665 UTC [74375] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC21582026/09/22 08:50:48 OK 20260905000000_add_claims.sql (71.82ms)21592026/09/22 08:50:48 OK 20260920000000_drop_claims.sql (40.05ms)21602026/09/22 08:50:48 goose: successfully migrated database to version: 2026092000000021612026/09/22 08:50:48 OK 1_commit_pending_closure.sql (1.44ms)21622026/09/22 08:50:48 OK 2_object_stats_trigger.sql (308.29µs)21632026/09/22 08:50:48 goose: up to current file version: 221642026/09/22 08:50:48 OK 20241026095416_initial_model.sql (206.01ms)21652026/09/22 08:50:48 OK 20251210153512_drop_unused_gin_index.sql (10.19ms)21662026/09/22 08:50:48 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/objects/present21672026/09/22 08:50:48 OK 20251218171726_add_pins.sql (30.76ms)21682026/09/22 08:50:49 OK 20260628120000_add_object_size_and_stats.sql (33.55ms)21692026/09/22 08:50:49 INFO Aborted multipart uploads count=021702026/09/22 08:50:49 WARN Force mode enabled - objects will be deleted immediately without grace period21712026/09/22 08:50:49 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=021722026/09/22 08:50:49 INFO Vacuumed table table=pending_closures21732026/09/22 08:50:49 INFO Vacuumed table table=pending_objects21742026/09/22 08:50:49 INFO Vacuumed table table=multipart_uploads21752026/09/22 08:50:49 INFO Vacuumed table table=closures21762026/09/22 08:50:49 INFO Vacuumed table table=objects2177--- PASS: TestGCMetrics (2.09s)2178=== CONT TestClientErrorHandling/InvalidAuthToken21792026/09/22 08:50:49 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=204.493075ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/objects/present21802026/09/22 08:50:49 OK 20260905000000_add_claims.sql (56.99ms)21812026-09-22 08:50:49.075 UTC [74380] ERROR: relation "goose_db_version" does not exist at character 3621822026-09-22 08:50:49.075 UTC [74380] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC21832026/09/22 08:50:49 OK 20260920000000_drop_claims.sql (32.86ms)21842026/09/22 08:50:49 goose: successfully migrated database to version: 2026092000000021852026/09/22 08:50:49 OK 1_commit_pending_closure.sql (1.94ms)21862026/09/22 08:50:49 OK 2_object_stats_trigger.sql (545.58µs)21872026/09/22 08:50:49 goose: up to current file version: 221882026-09-22 08:50:49.196 UTC [74384] ERROR: relation "goose_db_version" does not exist at character 3621892026-09-22 08:50:49.196 UTC [74384] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC21902026/09/22 08:50:49 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=439.836812ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/objects/present21912026/09/22 08:50:49 OK 20241026095416_initial_model.sql (191.23ms)21922026/09/22 08:50:49 OK 20251210153512_drop_unused_gin_index.sql (11.24ms)21932026-09-22 08:50:49.333 UTC [74385] ERROR: relation "goose_db_version" does not exist at character 3621942026-09-22 08:50:49.333 UTC [74385] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC21952026/09/22 08:50:49 OK 20251218171726_add_pins.sql (23.44ms)21962026/09/22 08:50:49 OK 20260628120000_add_object_size_and_stats.sql (33.15ms)2197--- PASS: TestService_ReadAuthMiddleware (2.28s)2198=== CONT TestIsValidCachePath/narinfo2199=== CONT TestIsValidCachePath/index.html2200=== CONT TestIsValidCachePath/nix-cache-info2201=== CONT TestIsValidCachePath/realisation2202=== CONT TestIsValidCachePath/log2203=== CONT TestIsValidCachePath/ls2204=== CONT TestIsValidCachePath/traversal_parent2205=== CONT TestIsValidCachePath/nar_uncompressed2206=== CONT TestIsValidCachePath/nar_bz22207=== CONT TestIsValidCachePath/nar_xz2208=== CONT TestIsValidCachePath/nar_zst2209=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars2210=== CONT TestIsValidCachePath/empty2211=== CONT TestIsValidCachePath/short_hash2212=== CONT TestIsValidCachePath/wrong_extension2213=== CONT TestIsValidCachePath/leading_slash2214=== CONT TestIsValidCachePath/invalid_char_u2215=== CONT TestIsValidCachePath/random_path2216=== CONT TestIsValidCachePath/invalid_char_e2217=== CONT TestIsValidCachePath/traversal_in_middle2218--- PASS: TestIsValidCachePath (0.00s)2219 --- PASS: TestIsValidCachePath/narinfo (0.00s)2220 --- PASS: TestIsValidCachePath/index.html (0.00s)2221 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)2222 --- PASS: TestIsValidCachePath/realisation (0.00s)2223 --- PASS: TestIsValidCachePath/log (0.00s)2224 --- PASS: TestIsValidCachePath/ls (0.00s)2225 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)2226 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)2227 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)2228 --- PASS: TestIsValidCachePath/nar_xz (0.00s)2229 --- PASS: TestIsValidCachePath/nar_zst (0.00s)2230 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)2231 --- PASS: TestIsValidCachePath/empty (0.00s)2232 --- PASS: TestIsValidCachePath/short_hash (0.00s)2233 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)2234 --- PASS: TestIsValidCachePath/leading_slash (0.00s)2235 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)2236 --- PASS: TestIsValidCachePath/random_path (0.00s)2237 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)2238 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)2239=== CONT TestParseSingleRange/none2240=== CONT TestParseSingleRange/open-ended2241=== CONT TestParseSingleRange/start_far_past_EOF2242=== CONT TestParseSingleRange/start_past_EOF2243=== CONT TestParseSingleRange/single_byte2244=== CONT TestParseSingleRange/suffix_exceeds_size2245=== CONT TestParseSingleRange/suffix2246=== CONT TestParseSingleRange/end_clamped_to_size2247=== CONT TestParseSingleRange/malformed_both_empty2248=== CONT TestParseSingleRange/closed2249=== CONT TestParseSingleRange/malformed_end_before_start2250=== CONT TestParseSingleRange/multi-range_ignored2251=== CONT TestParseSingleRange/malformed_no_dash2252=== CONT TestParseSingleRange/unknown_unit2253--- PASS: TestParseSingleRange (0.00s)2254 --- PASS: TestParseSingleRange/none (0.00s)2255 --- PASS: TestParseSingleRange/open-ended (0.00s)2256 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)2257 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)2258 --- PASS: TestParseSingleRange/single_byte (0.00s)2259 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)2260 --- PASS: TestParseSingleRange/suffix (0.00s)2261 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)2262 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)2263 --- PASS: TestParseSingleRange/closed (0.00s)2264 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)2265 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)2266 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)2267 --- PASS: TestParseSingleRange/unknown_unit (0.00s)2268=== CONT TestProxyWriteTimeout/narinfo2269=== CONT TestProxyWriteTimeout/10_GiB_nar2270=== CONT TestProxyWriteTimeout/unknown_size2271=== CONT TestProxyWriteTimeout/1_GiB_nar2272--- PASS: TestProxyWriteTimeout (0.00s)2273 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)2274 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)2275 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)2276 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)2277=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure22782026/09/22 08:50:49 INFO Received uploads request method=POST path=/22792026/09/22 08:50:49 OK 20241026095416_initial_model.sql (166.72ms)22802026/09/22 08:50:49 OK 20251210153512_drop_unused_gin_index.sql (18.64ms)22812026/09/22 08:50:49 OK 20260905000000_add_claims.sql (64.83ms)22822026/09/22 08:50:49 OK 20251218171726_add_pins.sql (16.99ms)22832026/09/22 08:50:49 OK 20260920000000_drop_claims.sql (8.49ms)22842026/09/22 08:50:49 goose: successfully migrated database to version: 2026092000000022852026/09/22 08:50:49 OK 1_commit_pending_closure.sql (2.55ms)22862026/09/22 08:50:49 OK 2_object_stats_trigger.sql (436.58µs)22872026/09/22 08:50:49 goose: up to current file version: 222882026/09/22 08:50:49 OK 20260628120000_add_object_size_and_stats.sql (19.52ms)22892026/09/22 08:50:49 OK 20241026095416_initial_model.sql (109.23ms)22902026/09/22 08:50:49 OK 20260905000000_add_claims.sql (55.16ms)22912026/09/22 08:50:49 OK 20251210153512_drop_unused_gin_index.sql (7.96ms)22922026/09/22 08:50:49 OK 20251218171726_add_pins.sql (29.09ms)22932026/09/22 08:50:49 OK 20260920000000_drop_claims.sql (29.43ms)22942026/09/22 08:50:49 goose: successfully migrated database to version: 2026092000000022952026/09/22 08:50:49 OK 1_commit_pending_closure.sql (995.88µs)22962026/09/22 08:50:49 OK 2_object_stats_trigger.sql (228.21µs)22972026/09/22 08:50:49 goose: up to current file version: 222982026/09/22 08:50:49 INFO Received complete multipart upload request method=POST path=/api/multipart/complete22992026/09/22 08:50:49 OK 20260628120000_add_object_size_and_stats.sql (24.58ms)23002026/09/22 08:50:49 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLmJlMzNjZjA5LWMwMDQtNDQzZC1hMWZlLTllZTk5NTNkNjM2MngxNzkwMDY3MDQ3OTQzNzQyMDAw parts=1223012026/09/22 08:50:49 INFO Received uploads request method=POST path=/api/pending_closures2302--- PASS: TestCompletedNarNotReofferedAcrossClosures (3.33s)2303=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts23042026/09/22 08:50:49 INFO Received request for more parts method=POST path=/23052026/09/22 08:50:49 OK 20260905000000_add_claims.sql (59.38ms)2306=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart23072026/09/22 08:50:49 INFO Received complete multipart upload request method=POST path=/23082026/09/22 08:50:49 OK 20260920000000_drop_claims.sql (12.61ms)23092026/09/22 08:50:49 goose: successfully migrated database to version: 2026092000000023102026/09/22 08:50:49 OK 1_commit_pending_closure.sql (1.33ms)23112026/09/22 08:50:49 OK 2_object_stats_trigger.sql (316.54µs)23122026/09/22 08:50:49 goose: up to current file version: 22313=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info23142026/09/22 08:50:49 INFO Received uploads request method=POST path=/2315=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key23162026/09/22 08:50:49 INFO Received complete multipart upload request method=POST path=/2317=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key23182026/09/22 08:50:49 INFO Received request for more parts method=POST path=/2319=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal23202026/09/22 08:50:49 INFO Received uploads request method=POST path=/2321--- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)2322 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)2323 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)2324 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)2325 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)2326=== CONT TestIsValidUploadKey/narinfo2327=== CONT TestIsValidUploadKey/unknown_type2328=== CONT TestIsValidUploadKey/empty_key2329=== CONT TestIsValidUploadKey/absolute2330=== CONT TestIsValidUploadKey/traversal_nar2331=== CONT TestIsValidUploadKey/traversal2332=== CONT TestIsValidUploadKey/listing_key,_narinfo_type2333=== CONT TestIsValidUploadKey/nar_key,_narinfo_type2334=== CONT TestIsValidUploadKey/narinfo_key,_nar_type2335=== CONT TestIsValidUploadKey/index.html2336=== CONT TestIsValidUploadKey/nix-cache-info2337=== CONT TestIsValidUploadKey/realisation_plus_in_output2338=== CONT TestIsValidUploadKey/realisation2339=== CONT TestIsValidUploadKey/build_log_equals2340=== CONT TestIsValidUploadKey/build_log_question_mark2341=== CONT TestIsValidUploadKey/build_log_plus_in_name2342=== CONT TestIsValidUploadKey/build_log_home-manager_file2343=== CONT TestIsValidUploadKey/build_log2344=== CONT TestIsValidUploadKey/listing2345=== CONT TestIsValidUploadKey/nar_plain2346=== CONT TestIsValidUploadKey/nar_xz2347=== CONT TestIsValidUploadKey/nar_zst2348--- PASS: TestIsValidUploadKey (0.00s)2349 --- PASS: TestIsValidUploadKey/narinfo (0.00s)2350 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)2351 --- PASS: TestIsValidUploadKey/empty_key (0.00s)2352 --- PASS: TestIsValidUploadKey/absolute (0.00s)2353 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)2354 --- PASS: TestIsValidUploadKey/traversal (0.00s)2355 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)2356 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)2357 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)2358 --- PASS: TestIsValidUploadKey/index.html (0.00s)2359 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)2360 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)2361 --- PASS: TestIsValidUploadKey/realisation (0.00s)2362 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)2363 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)2364 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)2365 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)2366 --- PASS: TestIsValidUploadKey/build_log (0.00s)2367 --- PASS: TestIsValidUploadKey/listing (0.00s)2368 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)2369 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)2370 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)2371=== CONT TestResolveDBConnectionString/flag_wins2372=== CONT TestResolveDBConnectionString/PGHOST_allows_empty2373=== CONT TestResolveDBConnectionString/nothing_configured2374=== CONT TestResolveDBConnectionString/missing_file_is_an_error2375=== CONT TestResolveDBConnectionString/file_when_flag_empty2376=== CONT TestCacheConfigHandler/full_config,_no_issuer2377=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator2378=== CONT TestCacheConfigHandler/no_signing_keys2379=== CONT TestCacheConfigHandler/no_cache_url_configured2380--- PASS: TestCacheConfigHandler (0.00s)2381 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)2382 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)2383 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)2384 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)2385=== CONT TestService_RequireScope_OIDC/builder_may_write2386=== CONT TestService_RequireScope_OIDC/static_token_may_admin2387=== CONT TestService_RequireScope_OIDC/anonymous_may_not_read2388=== CONT TestService_RequireScope_OIDC/writer_implies_read2389=== CONT TestService_RequireScope_OIDC/reader_may_read2390=== CONT TestService_RequireScope_OIDC/static_token_may_write2391=== CONT TestService_RequireScope_OIDC/ops_may_not_write2392=== CONT TestService_RequireScope_OIDC/reader_may_not_write2393=== CONT TestService_RequireScope_OIDC/ops_may_admin2394=== CONT TestService_RequireScope_OIDC/builder_may_not_admin2395--- PASS: TestResolveDBConnectionString (0.01s)2396 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)2397 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)2398 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)2399 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)2400 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)2401--- PASS: TestService_RequireScope_OIDC (1.91s)2402 --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)2403 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)2404 --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)2405 --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)2406 --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)2407 --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)2408 --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)2409 --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)2410 --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)2411 --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)2412--- PASS: TestUploadHandlersRejectOversizedBody (0.03s)2413 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)2414 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)2415 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.28s)2416=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token2417=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token2418=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected2419=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected2420=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected2421=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected2422=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured2423=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured2424=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token2425=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected2426=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured24272026/09/22 08:50:49 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]2428=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected24292026/09/22 08:50:49 WARN Authentication failed token_preview=eyJhbGciOi...kj3B2f-8Vg token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2430--- PASS: TestService_AuthMiddleware_OIDC (2.48s)2431 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)2432 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)2433 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)2434 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)24352026/09/22 08:50:49 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=783.09579ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/objects/present24362026-09-22 08:50:49.757 UTC [74386] ERROR: relation "goose_db_version" does not exist at character 3624372026-09-22 08:50:49.757 UTC [74386] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC24382026/09/22 08:50:49 INFO Received complete multipart upload request method=POST path=/api/multipart/complete24392026/09/22 08:50:49 OK 20241026095416_initial_model.sql (84.83ms)24402026/09/22 08:50:49 OK 20251210153512_drop_unused_gin_index.sql (10.97ms)24412026/09/22 08:50:49 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=MWRiOTdjODEtNDFjYy00N2NjLTlmZGUtODE2Yjk3YzQ0YTJjLjg2NzlmM2U4LWI0ZmMtNDM5MC1iNTc0LTFjNDg4YzJiMjk4YngxNzkwMDY3MDQ4MjgzMDI0MDAw parts=122442--- PASS: TestRedundantMultipartUpload (3.26s)24432026/09/22 08:50:49 OK 20251218171726_add_pins.sql (18.02ms)24442026/09/22 08:50:49 OK 20260628120000_add_object_size_and_stats.sql (29.04ms)24452026/09/22 08:50:49 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"24462026/09/22 08:50:49 WARN mTLS auth: bound subjects configured but subject DN unavailable24472026/09/22 08:50:49 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"2448--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (2.59s)24492026/09/22 08:50:49 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3003 objects_failed=02450=== NAME TestClientIntegration2451 client_integration_test.go:324: Objects in database after GC:2452 client_integration_test.go:324: Successfully deleted all objects with GC --force24532026/09/22 08:50:49 OK 20260905000000_add_claims.sql (43.49ms)2454--- PASS: TestClientIntegration (4.16s)24552026/09/22 08:50:49 OK 20260920000000_drop_claims.sql (24.58ms)24562026/09/22 08:50:49 goose: successfully migrated database to version: 2026092000000024572026/09/22 08:50:49 OK 1_commit_pending_closure.sql (4.57ms)24582026/09/22 08:50:49 OK 2_object_stats_trigger.sql (1.12ms)24592026/09/22 08:50:49 goose: up to current file version: 22460--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (2.58s)24612026-09-22 08:50:50.249 UTC [74387] ERROR: relation "goose_db_version" does not exist at character 3624622026-09-22 08:50:50.249 UTC [74387] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC24632026/09/22 08:50:50 OK 20241026095416_initial_model.sql (15.48ms)24642026/09/22 08:50:50 OK 20251210153512_drop_unused_gin_index.sql (835.67µs)24652026/09/22 08:50:50 OK 20251218171726_add_pins.sql (1.53ms)24662026/09/22 08:50:50 OK 20260628120000_add_object_size_and_stats.sql (1.81ms)24672026/09/22 08:50:50 OK 20260905000000_add_claims.sql (1.85ms)24682026/09/22 08:50:50 OK 20260920000000_drop_claims.sql (1.18ms)24692026/09/22 08:50:50 goose: successfully migrated database to version: 2026092000000024702026/09/22 08:50:50 OK 1_commit_pending_closure.sql (1.8ms)24712026/09/22 08:50:50 OK 2_object_stats_trigger.sql (340.46µs)24722026/09/22 08:50:50 goose: up to current file version: 224732026/09/22 08:50:50 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"24742026/09/22 08:50:50 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.591874018s error="Post \"http://localhost:19999/api/objects/present\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/objects/present24752026/09/22 08:50:50 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: server returned 404: 404 page not found\n"24762026/09/22 08:50:50 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"24772026/09/22 08:50:52 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/cache-config24782026/09/22 08:50:52 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=205.427051ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/cache-config24792026/09/22 08:50:52 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=363.165683ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/cache-config24802026/09/22 08:50:52 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=757.897588ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/cache-config24812026/09/22 08:50:53 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.538643188s error="Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/cache-config24822026/09/22 08:50:55 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: sending request: request failed after retries: Get \"http://localhost:19999/api/cache-config\": dial tcp 127.0.0.1:19999: connect: connection refused"24832026/09/22 08:50:55 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures24842026/09/22 08:50:55 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=183.192866ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures24852026/09/22 08:50:55 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=402.474881ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures24862026/09/22 08:50:55 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=834.891299ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures24872026/09/22 08:50:56 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.666790045s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures2488--- PASS: TestClientErrorHandling (0.00s)2489 --- PASS: TestClientErrorHandling/InvalidStorePath (2.44s)2490 --- PASS: TestClientErrorHandling/InvalidAuthToken (1.50s)2491 --- PASS: TestClientErrorHandling/ServerNotAvailable (9.83s)2492PASS2493{"timestamp":"2026-09-22T08:50:58.43495Z","level":"ERROR","message":"HTTP transport failed","event":"http_transport_failed","component":"server","subsystem":"transport","peer_addr":"127.0.0.1:53119","error_kind":"io_error","error":"Cancelled","result":"transport_error","target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":2260,"threadName":"rustfs-worker","threadId":"ThreadId(7)"}24942026-09-22 08:50:58.541 UTC [74022] LOG: received smart shutdown request24952026-09-22 08:50:58.542 UTC [74022] LOG: background worker "logical replication launcher" (PID 74032) exited with exit code 124962026-09-22 08:50:58.551 UTC [74027] LOG: shutting down24972026-09-22 08:50:58.551 UTC [74027] LOG: checkpoint starting: shutdown immediate24982026-09-22 08:50:59.670 UTC [74027] LOG: checkpoint complete: wrote 12885 buffers (78.6%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 16 recycled; write=0.766 s, sync=0.326 s, total=1.120 s; sync files=19409, longest=0.001 s, average=0.001 s; distance=269569 kB, estimate=269569 kB; lsn=0/11ECD3F8, redo lsn=0/11ECD3F824992026-09-22 08:50:59.674 UTC [74022] LOG: database system is shut down2500Running OIDC tests...2501=== RUN TestAudienceForIssuer2502=== PAUSE TestAudienceForIssuer2503=== RUN TestGlobMatch2504=== PAUSE TestGlobMatch2505=== RUN TestValidateToken_ValidToken2506=== PAUSE TestValidateToken_ValidToken2507=== RUN TestValidateToken_WrongAudience2508=== PAUSE TestValidateToken_WrongAudience2509=== RUN TestValidateToken_Expired2510=== PAUSE TestValidateToken_Expired2511=== RUN TestValidateToken_BoundClaimsMismatch2512=== PAUSE TestValidateToken_BoundClaimsMismatch2513=== RUN TestValidateToken_BoundSubjectMismatch2514=== PAUSE TestValidateToken_BoundSubjectMismatch2515=== RUN TestValidateToken_MultipleProviders2516=== PAUSE TestValidateToken_MultipleProviders2517=== RUN TestValidateToken_NoMatchingProvider2518=== PAUSE TestValidateToken_NoMatchingProvider2519=== RUN TestValidateToken_KubernetesServiceAccount2520=== PAUSE TestValidateToken_KubernetesServiceAccount2521=== RUN TestNewValidator_KubernetesRequiresCA2522=== PAUSE TestNewValidator_KubernetesRequiresCA2523=== RUN TestValidateToken_KubernetesIssuerFromOwnToken2524=== PAUSE TestValidateToken_KubernetesIssuerFromOwnToken2525=== RUN TestPins_ReservedForMatchingRule2526=== PAUSE TestPins_ReservedForMatchingRule2527=== RUN TestPins_TopLevelShorthand2528=== PAUSE TestPins_TopLevelShorthand2529=== RUN TestPins_ConfigValidation2530=== PAUSE TestPins_ConfigValidation2531=== RUN TestScopes_LegacyProviderDefaultsToWrite2532=== PAUSE TestScopes_LegacyProviderDefaultsToWrite2533=== RUN TestScopes_Rules2534=== PAUSE TestScopes_Rules2535=== RUN TestScopes_ConfigValidation2536=== PAUSE TestScopes_ConfigValidation2537=== CONT TestAudienceForIssuer2538--- PASS: TestAudienceForIssuer (0.00s)2539=== CONT TestValidateToken_NoMatchingProvider2540=== CONT TestPins_ConfigValidation2541=== CONT TestValidateToken_KubernetesServiceAccount2542=== CONT TestPins_ReservedForMatchingRule2543=== CONT TestScopes_ConfigValidation2544=== CONT TestValidateToken_KubernetesIssuerFromOwnToken2545=== CONT TestScopes_LegacyProviderDefaultsToWrite2546=== CONT TestScopes_Rules2547=== CONT TestNewValidator_KubernetesRequiresCA2548=== CONT TestPins_TopLevelShorthand2549--- PASS: TestScopes_ConfigValidation (0.00s)2550=== CONT TestValidateToken_Expired2551--- PASS: TestPins_ConfigValidation (0.01s)2552=== CONT TestValidateToken_MultipleProviders25532026/09/22 08:51:00 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC1232554--- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.04s)2555=== CONT TestValidateToken_BoundSubjectMismatch25562026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53277/oidc25572026/09/22 08:51:00 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:53275/oidc25582026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53280/oidc25592026/09/22 08:51:00 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:53281/oidc25602026/09/22 08:51:00 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:532762561--- PASS: TestScopes_Rules (0.07s)2562=== CONT TestValidateToken_BoundClaimsMismatch2563--- PASS: TestValidateToken_MultipleProviders (0.06s)2564=== CONT TestValidateToken_ValidToken25652026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53285/oidc2566--- PASS: TestPins_ReservedForMatchingRule (0.07s)2567=== CONT TestValidateToken_WrongAudience2568--- PASS: TestValidateToken_KubernetesServiceAccount (0.07s)2569=== CONT TestGlobMatch2570=== RUN TestGlobMatch/foo_foo2571=== PAUSE TestGlobMatch/foo_foo2572=== RUN TestGlobMatch/foo_bar2573=== PAUSE TestGlobMatch/foo_bar2574=== RUN TestGlobMatch/*_2575=== PAUSE TestGlobMatch/*_2576=== RUN TestGlobMatch/*_anything2577=== PAUSE TestGlobMatch/*_anything2578=== RUN TestGlobMatch/foo*_foo2579=== PAUSE TestGlobMatch/foo*_foo2580=== RUN TestGlobMatch/foo*_foobar2581=== PAUSE TestGlobMatch/foo*_foobar2582=== RUN TestGlobMatch/foo*_bar2583=== PAUSE TestGlobMatch/foo*_bar2584=== RUN TestGlobMatch/*bar_bar2585=== PAUSE TestGlobMatch/*bar_bar2586=== RUN TestGlobMatch/*bar_foobar2587=== PAUSE TestGlobMatch/*bar_foobar2588=== RUN TestGlobMatch/*bar_foo2589=== PAUSE TestGlobMatch/*bar_foo2590=== RUN TestGlobMatch/foo*bar_foobar2591=== PAUSE TestGlobMatch/foo*bar_foobar2592=== RUN TestGlobMatch/foo*bar_foo123bar2593=== PAUSE TestGlobMatch/foo*bar_foo123bar2594=== RUN TestGlobMatch/foo*bar_foobarbaz2595=== PAUSE TestGlobMatch/foo*bar_foobarbaz2596=== RUN TestGlobMatch/*/*_foo/bar2597=== PAUSE TestGlobMatch/*/*_foo/bar2598=== RUN TestGlobMatch/*/*_foo2599=== PAUSE TestGlobMatch/*/*_foo2600=== RUN TestGlobMatch/refs/heads/*_refs/heads/main2601=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main2602=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.02603=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.02604=== RUN TestGlobMatch/refs/*/main_refs/heads/main2605=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main2606=== RUN TestGlobMatch/fo?_foo2607=== PAUSE TestGlobMatch/fo?_foo2608=== RUN TestGlobMatch/fo?_fo2609=== PAUSE TestGlobMatch/fo?_fo2610=== RUN TestGlobMatch/fo?_fooo2611=== PAUSE TestGlobMatch/fo?_fooo2612=== RUN TestGlobMatch/?oo_foo2613=== PAUSE TestGlobMatch/?oo_foo2614=== RUN TestGlobMatch/?oo_boo2615=== PAUSE TestGlobMatch/?oo_boo2616=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2617=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2618=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2619=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2620=== CONT TestGlobMatch/foo_foo2621=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2622=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2623=== CONT TestGlobMatch/?oo_boo2624=== CONT TestGlobMatch/?oo_foo2625=== CONT TestGlobMatch/fo?_fooo2626=== CONT TestGlobMatch/fo?_fo2627=== CONT TestGlobMatch/fo?_foo2628=== CONT TestGlobMatch/refs/*/main_refs/heads/main2629=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.02630=== CONT TestGlobMatch/refs/heads/*_refs/heads/main2631=== CONT TestGlobMatch/*/*_foo2632=== CONT TestGlobMatch/*/*_foo/bar2633=== CONT TestGlobMatch/foo*bar_foobarbaz2634=== CONT TestGlobMatch/foo*bar_foo123bar2635=== CONT TestGlobMatch/foo*bar_foobar2636=== CONT TestGlobMatch/*bar_foo2637=== CONT TestGlobMatch/*bar_foobar2638=== CONT TestGlobMatch/*bar_bar2639=== CONT TestGlobMatch/foo*_bar2640=== CONT TestGlobMatch/foo*_foobar2641=== CONT TestGlobMatch/foo*_foo2642=== CONT TestGlobMatch/*_anything2643=== CONT TestGlobMatch/*_2644=== CONT TestGlobMatch/foo_bar2645--- PASS: TestGlobMatch (0.00s)2646 --- PASS: TestGlobMatch/foo_foo (0.00s)2647 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)2648 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)2649 --- PASS: TestGlobMatch/?oo_boo (0.00s)2650 --- PASS: TestGlobMatch/?oo_foo (0.00s)2651 --- PASS: TestGlobMatch/fo?_fooo (0.00s)2652 --- PASS: TestGlobMatch/fo?_fo (0.00s)2653 --- PASS: TestGlobMatch/fo?_foo (0.00s)2654 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)2655 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)2656 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)2657 --- PASS: TestGlobMatch/*/*_foo (0.00s)2658 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)2659 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)2660 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)2661 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)2662 --- PASS: TestGlobMatch/*bar_foo (0.00s)2663 --- PASS: TestGlobMatch/*bar_foobar (0.00s)2664 --- PASS: TestGlobMatch/*bar_bar (0.00s)2665 --- PASS: TestGlobMatch/foo*_bar (0.00s)2666 --- PASS: TestGlobMatch/foo*_foobar (0.00s)2667 --- PASS: TestGlobMatch/foo*_foo (0.00s)2668 --- PASS: TestGlobMatch/*_anything (0.00s)2669 --- PASS: TestGlobMatch/*_ (0.00s)2670 --- PASS: TestGlobMatch/foo_bar (0.00s)2671--- PASS: TestValidateToken_BoundSubjectMismatch (0.03s)26722026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53288/oidc2673--- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)26742026/09/22 08:51:00 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:53274/oidc26752026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53294/oidc2676--- PASS: TestValidateToken_NoMatchingProvider (0.11s)26772026/09/22 08:51:00 http: TLS handshake error from 127.0.0.1:53291: remote error: tls: bad certificate2678--- PASS: TestNewValidator_KubernetesRequiresCA (0.11s)2679--- PASS: TestPins_TopLevelShorthand (0.11s)26802026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53297/oidc2681--- PASS: TestValidateToken_ValidToken (0.05s)26822026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53299/oidc2683--- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.12s)26842026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53301/oidc2685--- PASS: TestValidateToken_Expired (0.12s)26862026/09/22 08:51:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53303/oidc2687--- PASS: TestValidateToken_WrongAudience (0.08s)2688PASS2689Running hook tests...2690=== RUN TestSendPathsEmpty2691=== PAUSE TestSendPathsEmpty2692=== RUN TestQueueEnqueueAndFetch2693=== PAUSE TestQueueEnqueueAndFetch2694=== RUN TestQueueDeduplication2695=== PAUSE TestQueueDeduplication2696=== RUN TestQueueRemove2697=== PAUSE TestQueueRemove2698=== RUN TestQueueFetchBatchLimit2699=== PAUSE TestQueueFetchBatchLimit2700=== RUN TestQueueRetryMovesToBack2701=== PAUSE TestQueueRetryMovesToBack2702=== RUN TestQueueFetchRemoveLifecycle2703=== PAUSE TestQueueFetchRemoveLifecycle2704=== RUN TestQueueConcurrentWriters2705=== PAUSE TestQueueConcurrentWriters2706=== RUN TestQueueRemoveLargeClosure2707=== PAUSE TestQueueRemoveLargeClosure2708=== RUN TestServerClientIntegration2709=== PAUSE TestServerClientIntegration2710=== RUN TestServerQueueError2711=== PAUSE TestServerQueueError2712=== RUN TestGetListenerSocketActivation2713 server_test.go:210: === RUN TestGetListenerSocketActivation2714 --- PASS: TestGetListenerSocketActivation (0.00s)2715 PASS2716 2717--- PASS: TestGetListenerSocketActivation (0.01s)2718=== RUN TestDrainIsolatesPoisonPath2719=== PAUSE TestDrainIsolatesPoisonPath2720=== RUN TestRunNotBlockedByPoisonHead2721=== PAUSE TestRunNotBlockedByPoisonHead2722=== RUN TestDrainGivesUpWhenServerDown2723=== PAUSE TestDrainGivesUpWhenServerDown2724=== RUN TestFailedPathPrunedByLaterClosure2725=== PAUSE TestFailedPathPrunedByLaterClosure2726=== RUN TestWorkerUploadsAndRemoves2727=== PAUSE TestWorkerUploadsAndRemoves2728=== RUN TestWorkerSkipsGCdPaths2729=== PAUSE TestWorkerSkipsGCdPaths2730=== RUN TestWorkerPrunesClosureDeps2731=== PAUSE TestWorkerPrunesClosureDeps2732=== RUN TestDrainTimeout2733=== PAUSE TestDrainTimeout2734=== CONT TestSendPathsEmpty2735=== CONT TestServerQueueError2736--- PASS: TestSendPathsEmpty (0.00s)2737=== CONT TestQueueFetchBatchLimit2738=== CONT TestQueueRetryMovesToBack2739=== CONT TestQueueRemoveLargeClosure2740=== CONT TestServerClientIntegration2741=== CONT TestQueueDeduplication2742=== CONT TestWorkerUploadsAndRemoves2743=== CONT TestWorkerPrunesClosureDeps2744=== CONT TestQueueConcurrentWriters2745=== CONT TestDrainGivesUpWhenServerDown27462026/09/22 08:51:01 ERROR Failed to queue paths error="permission denied" count=12747--- PASS: TestServerQueueError (0.00s)2748=== CONT TestDrainTimeout2749--- PASS: TestServerClientIntegration (0.00s)2750=== CONT TestRunNotBlockedByPoisonHead27512026/09/22 08:51:01 INFO Upload queue status pending=227522026/09/22 08:51:01 INFO Uploading batch count=227532026/09/22 08:51:01 INFO Uploading batch count=22754--- PASS: TestQueueDeduplication (0.01s)2755=== CONT TestQueueFetchRemoveLifecycle27562026/09/22 08:51:01 INFO Upload queue status pending=227572026/09/22 08:51:01 INFO Uploading batch count=12758--- PASS: TestQueueRetryMovesToBack (0.01s)2759=== CONT TestWorkerSkipsGCdPaths27602026/09/22 08:51:01 INFO Upload queue status pending=327612026/09/22 08:51:01 INFO Uploading batch count=127622026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=12763--- PASS: TestQueueFetchBatchLimit (0.01s)2764=== CONT TestQueueEnqueueAndFetch27652026/09/22 08:51:01 INFO Uploading batch count=227662026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=227672026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/a27682026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/b27692026/09/22 08:51:01 INFO Uploading batch count=227702026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=227712026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/c27722026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/d27732026/09/22 08:51:01 INFO Uploading batch count=227742026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=227752026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/e27762026/09/22 08:51:01 INFO Upload queue status pending=227772026/09/22 08:51:01 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-73934-291241723/TestWorkerSkipsGCdPaths2676794494/002/nonexistent27782026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainGivesUpWhenServerDown2585879273/002/f27792026/09/22 08:51:01 INFO Uploading batch count=127802026/09/22 08:51:01 ERROR Drain finished with paths left in queue remaining=102781--- PASS: TestQueueEnqueueAndFetch (0.00s)2782=== CONT TestFailedPathPrunedByLaterClosure2783--- PASS: TestQueueFetchRemoveLifecycle (0.00s)2784=== CONT TestQueueRemove2785--- PASS: TestDrainGivesUpWhenServerDown (0.01s)2786=== CONT TestDrainIsolatesPoisonPath27872026/09/22 08:51:01 INFO Uploading batch count=127882026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=127892026/09/22 08:51:01 INFO Uploading batch count=127902026/09/22 08:51:01 INFO Uploading batch count=12791--- PASS: TestQueueRemove (0.00s)27922026/09/22 08:51:01 INFO Uploading batch count=427932026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=427942026/09/22 08:51:01 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-73934-291241723/TestDrainIsolatesPoisonPath3462824238/002/bbb27952026/09/22 08:51:01 INFO Uploading batch count=127962026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=12797--- PASS: TestFailedPathPrunedByLaterClosure (0.00s)27982026/09/22 08:51:01 INFO Uploading batch count=127992026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=128002026/09/22 08:51:01 INFO Uploading batch count=128012026/09/22 08:51:01 ERROR Upload failed error="upload failed" count=128022026/09/22 08:51:01 ERROR Drain finished with paths left in queue remaining=12803--- PASS: TestDrainIsolatesPoisonPath (0.00s)2804--- PASS: TestWorkerUploadsAndRemoves (0.03s)2805--- PASS: TestWorkerPrunesClosureDeps (0.03s)2806--- PASS: TestWorkerSkipsGCdPaths (0.02s)2807--- PASS: TestQueueRemoveLargeClosure (0.06s)2808--- PASS: TestQueueConcurrentWriters (0.15s)28092026/09/22 08:51:01 ERROR Upload failed error="context deadline exceeded" count=228102026/09/22 08:51:01 ERROR Drain finished with paths left in queue remaining=42811--- PASS: TestDrainTimeout (0.21s)28122026/09/22 08:51:02 INFO Uploading batch count=128132026/09/22 08:51:02 INFO Uploading batch count=128142026/09/22 08:51:02 INFO Uploading batch count=128152026/09/22 08:51:02 ERROR Upload failed error="upload failed" count=128162026/09/22 08:51:02 INFO Uploading batch count=128172026/09/22 08:51:02 ERROR Upload failed error="upload failed" count=128182026/09/22 08:51:02 INFO Uploading batch count=128192026/09/22 08:51:02 ERROR Upload failed error="upload failed" count=128202026/09/22 08:51:02 INFO Uploading batch count=128212026/09/22 08:51:02 ERROR Upload failed error="upload failed" count=128222026/09/22 08:51:02 ERROR Drain finished with paths left in queue remaining=12823--- PASS: TestRunNotBlockedByPoisonHead (1.02s)2824PASS