niks3-go-unit-tests
checks.aarch64-linux.go-unit-tests
· build #409
· raw
1tribuchet: building on eliza2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestUploadBuildLog_FileBodyReplayedOnRetry6=== PAUSE TestUploadBuildLog_FileBodyReplayedOnRetry7=== RUN TestRegisterUploadedObjectReusesConnections8=== PAUSE TestRegisterUploadedObjectReusesConnections9=== RUN TestRunGarbageCollection_FinishedOnAnotherReplica10=== PAUSE TestRunGarbageCollection_FinishedOnAnotherReplica11=== RUN TestRunGarbageCollection_NotFoundAfterLocalRun12=== PAUSE TestRunGarbageCollection_NotFoundAfterLocalRun13=== RUN TestCaseHackSuffix14=== PAUSE TestCaseHackSuffix15=== RUN TestFilterOversizedClosures16=== PAUSE TestFilterOversizedClosures17=== RUN TestUploadMultipart_PartsInParallel18=== PAUSE TestUploadMultipart_PartsInParallel19=== RUN TestPartSizeForNAR20=== PAUSE TestPartSizeForNAR21=== RUN TestUploadMultipart_SupersededByPeer22=== PAUSE TestUploadMultipart_SupersededByPeer23=== RUN TestDumpPathCaseHackMatchesNix24--- PASS: TestDumpPathCaseHackMatchesNix (0.05s)25=== RUN TestDumpPathCaseHackCollision26--- PASS: TestDumpPathCaseHackCollision (0.00s)27=== RUN TestSupersededNARStillUploadsListing28=== PAUSE TestSupersededNARStillUploadsListing29=== RUN TestDumpPathMatchesNix30=== PAUSE TestDumpPathMatchesNix31=== RUN TestDumpPathSingleFile32=== PAUSE TestDumpPathSingleFile33=== RUN TestDumpPathWriterError34=== PAUSE TestDumpPathWriterError35=== RUN TestEncodeNixBase3236=== PAUSE TestEncodeNixBase3237=== RUN TestEncodeNixBase32WithRealHash38=== PAUSE TestEncodeNixBase32WithRealHash39=== RUN TestConvertHashToNix3240=== PAUSE TestConvertHashToNix3241=== RUN TestGetStorePathHash42=== PAUSE TestGetStorePathHash43=== RUN TestPathInfoHashCompatibility44=== PAUSE TestPathInfoHashCompatibility45=== RUN TestParsePathInfoJSON46=== PAUSE TestParsePathInfoJSON47=== RUN TestParsePathInfoJSONMultiplePaths48=== PAUSE TestParsePathInfoJSONMultiplePaths49=== RUN TestPathInfoCACompatibility50=== PAUSE TestPathInfoCACompatibility51=== RUN TestRateLimiterFeedback52=== PAUSE TestRateLimiterFeedback53=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess54=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess55=== RUN TestRegisterUploadedObject_BoundedAgainstSilentServer56=== PAUSE TestRegisterUploadedObject_BoundedAgainstSilentServer57=== RUN TestResolveStorePath58=== PAUSE TestResolveStorePath59=== RUN TestDoWithRetry_BodyReplayedViaGetBody60=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody61=== RUN TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade62=== PAUSE TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade63=== RUN TestRetryTimeoutOutlastsMaxRetries64=== PAUSE TestRetryTimeoutOutlastsMaxRetries65=== RUN TestDoWithRetry_FinalResponseBodyReadable66=== PAUSE TestDoWithRetry_FinalResponseBodyReadable67=== RUN TestShellSplit68=== PAUSE TestShellSplit69=== RUN TestShellSplitErrors70=== PAUSE TestShellSplitErrors71=== RUN TestStreamPushReportsEveryPath72=== PAUSE TestStreamPushReportsEveryPath73=== RUN TestStreamPushBatchesUnderLoad74=== PAUSE TestStreamPushBatchesUnderLoad75=== RUN TestStreamPushIsolatesFailures76=== PAUSE TestStreamPushIsolatesFailures77=== RUN TestStreamPushGivesUpOnDeadServer78=== PAUSE TestStreamPushGivesUpOnDeadServer79=== RUN TestStreamPushRequestLine80=== PAUSE TestStreamPushRequestLine81=== RUN TestStreamPushReportsSignatures82=== PAUSE TestStreamPushReportsSignatures83=== RUN TestStreamPushReportsSkippedPaths84=== PAUSE TestStreamPushReportsSkippedPaths85=== RUN TestClientSignaturesByStorePath86=== PAUSE TestClientSignaturesByStorePath87=== RUN TestStreamPushStopsWhenCanceled88=== PAUSE TestStreamPushStopsWhenCanceled89=== RUN TestSetClientTLS90=== PAUSE TestSetClientTLS91=== RUN TestSetClientTLSDoesNotMutateDefaultTransport92=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport93=== RUN TestSetClientTLSErrors94=== PAUSE TestSetClientTLSErrors95=== RUN TestStaticToken96=== PAUSE TestStaticToken97=== RUN TestFileTokenReadsAndCaches98=== PAUSE TestFileTokenReadsAndCaches99=== RUN TestFileTokenMissing100=== PAUSE TestFileTokenMissing101=== RUN TestFileTokenEmpty102=== PAUSE TestFileTokenEmpty103=== RUN TestScriptTokenNoExpiryRerunsEveryCall104=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall105=== RUN TestScriptTokenCachesUntilRefresh106=== PAUSE TestScriptTokenCachesUntilRefresh107=== RUN TestScriptTokenEmptyToken108=== PAUSE TestScriptTokenEmptyToken109=== RUN TestScriptTokenBadJSON110=== PAUSE TestScriptTokenBadJSON111=== RUN TestScriptTokenScriptFails112=== PAUSE TestScriptTokenScriptFails113=== RUN TestScriptTokenEmptyCommand114=== PAUSE TestScriptTokenEmptyCommand115=== CONT TestDoServerRequestAttachesToken116=== CONT TestEncodeNixBase32117=== RUN TestEncodeNixBase32/test_string_hash118=== PAUSE TestEncodeNixBase32/test_string_hash119=== RUN TestEncodeNixBase32/empty_input120=== PAUSE TestEncodeNixBase32/empty_input121=== CONT TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade122=== RUN TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=0123=== PAUSE TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=0124=== RUN TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=5125=== PAUSE TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=5126=== CONT TestStreamPushReportsSkippedPaths127--- PASS: TestStreamPushReportsSkippedPaths (0.00s)128=== CONT TestPartSizeForNAR129=== RUN TestPartSizeForNAR/zero_stays_at_minimum130=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum131=== RUN TestPartSizeForNAR/small_stays_at_minimum132=== PAUSE TestPartSizeForNAR/small_stays_at_minimum133=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum134=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum135=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts136=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts137=== RUN TestPartSizeForNAR/1_TiB138=== PAUSE TestPartSizeForNAR/1_TiB139=== RUN TestPartSizeForNAR/5_TiB_S3_max_object140=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object141=== RUN TestPartSizeForNAR/capped_at_5_GiB142=== PAUSE TestPartSizeForNAR/capped_at_5_GiB143=== CONT TestScriptTokenEmptyCommand144--- PASS: TestScriptTokenEmptyCommand (0.00s)145=== CONT TestStreamPushGivesUpOnDeadServer146=== CONT TestStreamPushReportsSignatures147=== CONT TestStreamPushRequestLine148=== CONT TestScriptTokenScriptFails149=== CONT TestScriptTokenBadJSON150=== CONT TestScriptTokenEmptyToken151=== CONT TestScriptTokenCachesUntilRefresh152=== CONT TestScriptTokenNoExpiryRerunsEveryCall153=== CONT TestFileTokenEmpty154=== CONT TestFileTokenMissing155=== CONT TestFileTokenReadsAndCaches156=== CONT TestStaticToken157=== CONT TestUploadMultipart_PartsInParallel158=== CONT TestSetClientTLSErrors159=== CONT TestDumpPathWriterError160=== CONT TestSetClientTLSDoesNotMutateDefaultTransport161=== CONT TestDumpPathSingleFile162=== CONT TestSetClientTLS163=== CONT TestDumpPathMatchesNix164=== CONT TestStreamPushStopsWhenCanceled165=== CONT TestSupersededNARStillUploadsListing166=== CONT TestClientSignaturesByStorePath167=== CONT TestUploadMultipart_SupersededByPeer1682026/10/11 04:52:56 ERROR Upload failed error=boom count=11692026/10/11 04:52:56 ERROR Upload failed error="connection refused" count=201702026/10/11 04:52:56 ERROR Server seems unavailable, giving up on batch untried=17171--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)172=== CONT TestRunGarbageCollection_NotFoundAfterLocalRun173=== CONT TestStreamPushIsolatesFailures1742026/10/11 04:52:56 ERROR Upload failed error=boom count=1175--- PASS: TestScriptTokenScriptFails (0.00s)176--- PASS: TestStreamPushReportsSignatures (0.00s)177=== CONT TestFilterOversizedClosures1782026/10/11 04:52:56 ERROR Upload failed error="bad path" count=3179--- PASS: TestStreamPushIsolatesFailures (0.00s)180=== CONT TestStreamPushBatchesUnderLoad181--- PASS: TestStaticToken (0.00s)182=== CONT TestCaseHackSuffix183--- PASS: TestDoServerRequestAttachesToken (0.01s)184=== CONT TestStreamPushReportsEveryPath185--- PASS: TestFileTokenEmpty (0.00s)186=== CONT TestShellSplitErrors187--- PASS: TestFileTokenReadsAndCaches (0.00s)188=== CONT TestDoWithRetry_FinalResponseBodyReadable189--- PASS: TestStreamPushStopsWhenCanceled (0.00s)190=== CONT TestRetryTimeoutOutlastsMaxRetries191=== RUN TestSupersededNARStillUploadsListing/small_NAR192=== PAUSE TestSupersededNARStillUploadsListing/small_NAR193=== RUN TestSupersededNARStillUploadsListing/dump_cut_short1942026/10/11 04:52:56 INFO Garbage collection started195=== PAUSE TestSupersededNARStillUploadsListing/dump_cut_short196=== RUN TestSupersededNARStillUploadsListing/listing_upload_fails197=== PAUSE TestSupersededNARStillUploadsListing/listing_upload_fails198=== CONT TestShellSplit199=== CONT TestRegisterUploadedObject_BoundedAgainstSilentServer200--- PASS: TestFileTokenMissing (0.02s)201--- PASS: TestClientSignaturesByStorePath (0.00s)202=== CONT TestRegisterUploadedObjectReusesConnections203=== RUN TestUploadMultipart_SupersededByPeer/exists204=== PAUSE TestUploadMultipart_SupersededByPeer/exists205=== RUN TestUploadMultipart_SupersededByPeer/missing206=== PAUSE TestUploadMultipart_SupersededByPeer/missing2072026/10/11 04:52:56 WARN Request returned retryable status, retrying attempt=1 backoff=1ms status=502 url=http://127.0.0.1:37991208=== CONT TestPathInfoHashCompatibility209=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)210=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)211=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon212=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon213=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI214=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI215=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512216=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512217--- PASS: TestShellSplit (0.00s)2182026/10/11 04:52:56 WARN Rate limiter enabled after throttle name=server-test rate=5219--- PASS: TestScriptTokenEmptyToken (0.03s)220--- PASS: TestScriptTokenCachesUntilRefresh (0.03s)221=== CONT TestDoWithRetry_BodyReplayedViaGetBody222=== CONT TestResolveStorePath223--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.06s)224=== CONT TestParsePathInfoJSONMultiplePaths225=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths226=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths227=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths228=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths229=== CONT TestGetStorePathHash230=== RUN TestGetStorePathHash/valid_store_path231=== PAUSE TestGetStorePathHash/valid_store_path232=== RUN TestGetStorePathHash/basename_without_hyphen_should_error2332026/10/11 04:52:56 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:43155234=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error235=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error236=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error237=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error238=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error239=== CONT TestParsePathInfoJSON240=== RUN TestParsePathInfoJSON/Nix_format241=== PAUSE TestParsePathInfoJSON/Nix_format242=== RUN TestParsePathInfoJSON/Lix_format243=== PAUSE TestParsePathInfoJSON/Lix_format244=== RUN TestParsePathInfoJSON/empty_input245=== PAUSE TestParsePathInfoJSON/empty_input246=== RUN TestParsePathInfoJSON/whitespace_only247=== PAUSE TestParsePathInfoJSON/whitespace_only248=== RUN TestParsePathInfoJSON/invalid_JSON249=== PAUSE TestParsePathInfoJSON/invalid_JSON250=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess251--- PASS: TestResolveStorePath (0.00s)252=== CONT TestEncodeNixBase32WithRealHash253--- PASS: TestEncodeNixBase32WithRealHash (0.01s)254=== CONT TestRateLimiterFeedback255=== RUN TestRateLimiterFeedback/429_enables_limiter256=== PAUSE TestRateLimiterFeedback/429_enables_limiter257=== RUN TestRateLimiterFeedback/503_enables_limiter258=== PAUSE TestRateLimiterFeedback/503_enables_limiter259=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter260=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter261=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter262=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter263=== RUN TestFilterOversizedClosures/no_limit_keeps_everything264=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything265=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped266=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped267=== RUN TestFilterOversizedClosures/all_closures_skipped268=== PAUSE TestFilterOversizedClosures/all_closures_skipped2692026/10/11 04:52:56 WARN Rate limiter enabled after throttle name=server-test rate=5270--- PASS: TestScriptTokenBadJSON (0.05s)271=== CONT TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=02722026/10/11 04:52:56 WARN Rate limiter enabled after throttle name=s3-test rate=5273=== CONT TestEncodeNixBase32/test_string_hash274=== CONT TestPartSizeForNAR/zero_stays_at_minimum275=== CONT TestUploadBuildLog_FileBodyReplayedOnRetry276--- PASS: TestShellSplitErrors (0.00s)277=== CONT TestPartSizeForNAR/capped_at_5_GiB278=== CONT TestPartSizeForNAR/5_TiB_S3_max_object279=== CONT TestPartSizeForNAR/1_TiB280=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts281=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum282=== CONT TestPartSizeForNAR/small_stays_at_minimum283--- PASS: TestPartSizeForNAR (0.00s)284 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)285 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)286 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)287 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)288 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)289 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)290 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)291=== CONT TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=5292=== CONT TestSupersededNARStillUploadsListing/dump_cut_short293=== CONT TestEncodeNixBase32/empty_input294=== CONT TestSupersededNARStillUploadsListing/listing_upload_fails2952026/10/11 04:52:56 WARN Rate limiter enabled after throttle name=s3-test rate=5296--- PASS: TestStreamPushReportsEveryPath (0.00s)297--- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.07s)298--- PASS: TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade (0.00s)299 --- PASS: TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=0 (0.00s)300 --- PASS: TestDoWithRetry_ClosesBodyWhenNoAttemptIsMade/retries=5 (0.00s)301--- PASS: TestEncodeNixBase32 (0.00s)302 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)303 --- PASS: TestEncodeNixBase32/empty_input (0.00s)304=== CONT TestUploadMultipart_SupersededByPeer/exists305=== CONT TestRunGarbageCollection_FinishedOnAnotherReplica306=== CONT TestPathInfoCACompatibility3072026/10/11 04:52:56 INFO Uploading abc-hello (1.0GB)3082026/10/11 04:52:56 WARN Rate limiter enabled after throttle name=server-test rate=53092026/10/11 04:52:56 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:42133310=== RUN TestPathInfoCACompatibility/null_ca_field311=== CONT TestSupersededNARStillUploadsListing/small_NAR312=== CONT TestConvertHashToNix32313=== PAUSE TestPathInfoCACompatibility/null_ca_field314--- PASS: TestCaseHackSuffix (0.08s)315=== RUN TestPathInfoCACompatibility/old_string_format_-_text3162026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Post \"http://127.0.0.1:45159/api/uploads/complete\": context deadline exceeded" url=http://127.0.0.1:45159/api/uploads/complete3172026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=2 backoff=1ms status=502 url=http://127.0.0.1:379913182026/10/11 04:52:57 WARN Failed to register uploaded object key=abc.narinfo error="sending request: context canceled during retry: context deadline exceeded"3192026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=1 backoff=1ms status=502 url=http://127.0.0.1:379913202026/10/11 04:52:57 INFO Uploading abc-hello (1.0GB)321=== RUN TestSetClientTLSErrors/missing_cert_file322=== PAUSE TestSetClientTLSErrors/missing_cert_file323=== RUN TestSetClientTLSErrors/missing_key_file324=== PAUSE TestSetClientTLSErrors/missing_key_file3252026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=2 backoff=1ms status=502 url=http://127.0.0.1:37991326=== RUN TestSetClientTLSErrors/missing_ca_file327=== PAUSE TestSetClientTLSErrors/missing_ca_file328=== RUN TestSetClientTLSErrors/invalid_ca_file329=== PAUSE TestSetClientTLSErrors/invalid_ca_file330=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths331=== CONT TestGetStorePathHash/valid_store_path332=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths333--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)334 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)335 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)336=== CONT TestParsePathInfoJSON/Nix_format337=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error338=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error339=== CONT TestGetStorePathHash/basename_without_hyphen_should_error340--- PASS: TestGetStorePathHash (0.00s)341 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)342 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)343 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)344 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)345=== CONT TestRateLimiterFeedback/429_enables_limiter3462026/10/11 04:52:57 WARN Rate limiter backed off name=server-test rate=5347--- PASS: TestDoWithRetry_FinalResponseBodyReadable (0.33s)348=== CONT TestUploadMultipart_SupersededByPeer/missing3492026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=3 backoff=1ms status=502 url=http://127.0.0.1:379913502026/10/11 04:52:57 WARN Rate limiter enabled after throttle name=server-test rate=53512026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=429 url=http://127.0.0.1:401173522026/10/11 04:52:57 WARN Rate limiter backed off name=server-test rate=5353=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text354=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive355=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive356=== RUN TestPathInfoCACompatibility/new_structured_format_-_text357=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text358=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method359=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method360=== CONT TestParsePathInfoJSON/whitespace_only361=== CONT TestParsePathInfoJSON/empty_input362=== CONT TestParsePathInfoJSON/Lix_format363=== CONT TestParsePathInfoJSON/invalid_JSON364=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped365=== CONT TestFilterOversizedClosures/all_closures_skipped3662026/10/11 04:52:57 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=2000367=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter3682026/10/11 04:52:57 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=50369=== CONT TestRateLimiterFeedback/503_enables_limiter3702026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=4 backoff=1ms status=502 url=http://127.0.0.1:37991371--- PASS: TestParsePathInfoJSON (0.00s)372 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)373 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)374 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)375 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)376 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)377=== RUN TestConvertHashToNix32/SRI_format_to_Nix32378=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32379=== RUN TestConvertHashToNix32/already_Nix32_format380=== PAUSE TestConvertHashToNix32/already_Nix32_format381=== RUN TestConvertHashToNix32/invalid_format382=== PAUSE TestConvertHashToNix32/invalid_format3832026/10/11 04:52:57 WARN Rate limiter enabled after throttle name=server-test rate=53842026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:40299385=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)3862026/10/11 04:52:57 INFO Garbage collection started387=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512388=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter389=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI390=== CONT TestSetClientTLSErrors/missing_cert_file391=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon392--- PASS: TestPathInfoHashCompatibility (0.00s)393 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)394 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)395 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)396 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)397=== CONT TestSetClientTLSErrors/invalid_ca_file3982026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=5 backoff=1ms status=502 url=http://127.0.0.1:37991399=== CONT TestSetClientTLSErrors/missing_key_file400=== CONT TestSetClientTLSErrors/missing_ca_file4012026/10/11 04:52:57 WARN Rate limiter backed off name=server-test rate=5402=== CONT TestConvertHashToNix32/invalid_format403=== CONT TestConvertHashToNix32/already_Nix32_format4042026/10/11 04:52:57 INFO Garbage collection progress phase=running_on_another_replica failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=0 objects_deleted=0 objects_failed=0405--- PASS: TestRateLimiterFeedback (0.00s)406 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)407 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)408 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)409 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)410--- PASS: TestSetClientTLSErrors (0.36s)411 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)412 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)413 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)414 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)415=== CONT TestPathInfoCACompatibility/null_ca_field416=== CONT TestConvertHashToNix32/SRI_format_to_Nix32417--- PASS: TestConvertHashToNix32 (0.00s)418 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)419 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)420 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)4212026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=6 backoff=1ms status=502 url=http://127.0.0.1:379914222026/10/11 04:52:57 WARN Garbage collection finished on another replica. Its statistics are not available here423--- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.29s)4242026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=7 backoff=1ms status=502 url=http://127.0.0.1:37991425=== CONT TestFilterOversizedClosures/no_limit_keeps_everything426--- PASS: TestFilterOversizedClosures (0.00s)427 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)428 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)429 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)430=== RUN TestSetClientTLS/rejects_connection_without_client_cert431=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert432=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA433=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA434=== RUN TestSetClientTLS/preserves_debug_logging_transport435=== PAUSE TestSetClientTLS/preserves_debug_logging_transport436=== CONT TestSetClientTLS/rejects_connection_without_client_cert437=== CONT TestPathInfoCACompatibility/new_structured_format_-_text438=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method4392026/10/11 04:52:57 WARN Rate limiter enabled after throttle name=s3-test rate=54402026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:43273441=== CONT TestSetClientTLS/preserves_debug_logging_transport442=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA443--- PASS: TestUploadBuildLog_FileBodyReplayedOnRetry (0.30s)4442026/10/11 04:52:57 WARN Rate limiter backed off name=server-test rate=54452026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=2 backoff=0s status=503 url=http://127.0.0.1:42133446--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.31s)447=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive448=== CONT TestPathInfoCACompatibility/old_string_format_-_text449--- PASS: TestPathInfoCACompatibility (0.28s)450 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)451 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)452 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)453 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)454 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)4552026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=8 backoff=1ms status=502 url=http://127.0.0.1:379914562026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Post \"http://127.0.0.1:45159/api/uploads/complete\": context deadline exceeded" url=http://127.0.0.1:45159/api/uploads/complete4572026/10/11 04:52:57 WARN Failed to register uploaded object key=abc.narinfo error="sending request: context canceled during retry: context deadline exceeded"4582026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=9 backoff=1ms status=502 url=http://127.0.0.1:37991459--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)460 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)461 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.30s)462--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.38s)463--- PASS: TestDumpPathSingleFile (0.38s)464--- PASS: TestStreamPushBatchesUnderLoad (0.38s)4652026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=10 backoff=1ms status=502 url=http://127.0.0.1:37991466--- PASS: TestStreamPushRequestLine (0.38s)4672026/10/11 04:52:57 http: TLS handshake error from 127.0.0.1:58606: remote error: tls: bad certificate468--- PASS: TestSetClientTLS (0.34s)469 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)470 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.01s)471 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)4722026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=11 backoff=1ms status=502 url=http://127.0.0.1:379914732026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Post \"http://127.0.0.1:45159/api/uploads/complete\": context deadline exceeded" url=http://127.0.0.1:45159/api/uploads/complete4742026/10/11 04:52:57 WARN Failed to register uploaded object key=abc.narinfo error="sending request: context canceled during retry: context deadline exceeded"475=== NAME TestRegisterUploadedObject_BoundedAgainstSilentServer476 register_upload_test.go:65: server saw 0 registrations, want 3477--- FAIL: TestRegisterUploadedObject_BoundedAgainstSilentServer (0.36s)4782026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=12 backoff=1ms status=502 url=http://127.0.0.1:379914792026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=13 backoff=1ms status=502 url=http://127.0.0.1:379914802026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=14 backoff=1ms status=502 url=http://127.0.0.1:37991481--- PASS: TestRegisterUploadedObjectReusesConnections (0.36s)4822026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=15 backoff=1ms status=502 url=http://127.0.0.1:379914832026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=16 backoff=1ms status=502 url=http://127.0.0.1:379914842026/10/11 04:52:57 WARN Request returned retryable status, retrying attempt=17 backoff=1ms status=502 url=http://127.0.0.1:37991485--- PASS: TestRetryTimeoutOutlastsMaxRetries (0.37s)486--- PASS: TestDumpPathWriterError (0.39s)4872026/10/11 04:52:57 INFO Uploading abc-hello (1.0GB)4882026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Put \"http://127.0.0.1:41229/part/2\": uploading part 1: server returned 404: no such upload\n" url=http://127.0.0.1:41229/part/2489--- PASS: TestDumpPathMatchesNix (0.41s)4902026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Put \"http://127.0.0.1:41229/part/3\": uploading part 1: server returned 404: no such upload\n" url=http://127.0.0.1:41229/part/34912026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Put \"http://127.0.0.1:41229/part/4\": uploading part 1: server returned 404: no such upload\n" url=http://127.0.0.1:41229/part/44922026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Put \"http://127.0.0.1:41229/part/5\": uploading part 1: server returned 404: no such upload\n" url=http://127.0.0.1:41229/part/54932026/10/11 04:52:57 WARN Request failed, retrying attempt=1 backoff=100ms error="Put \"http://127.0.0.1:41229/part/6\": uploading part 1: server returned 404: no such upload\n" url=http://127.0.0.1:41229/part/64942026/10/11 04:52:57 ERROR Failed to close zstd encoder error="io: read/write on closed pipe"495--- PASS: TestSupersededNARStillUploadsListing (0.00s)496 --- PASS: TestSupersededNARStillUploadsListing/listing_upload_fails (0.29s)497 --- PASS: TestSupersededNARStillUploadsListing/small_NAR (0.30s)498 --- PASS: TestSupersededNARStillUploadsListing/dump_cut_short (0.85s)499--- PASS: TestUploadMultipart_PartsInParallel (0.96s)500--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.12s)501FAIL