"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSizeOnly|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURLDownloadHeaders|TestDeduplicateFirst|TestDeduplicateSizeOnly|TestDirMove|TestDirsEqual|TestGetFsInfo|TestLsWithFilesFrom|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestSetDirModTime|TestTouchDir)$|^TestCheckDownload$/^6$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5) 2026/08/11 05:39:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vodihir0fori" 2026/08/11 05:39:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:39:05 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Using cached web token 2026/08/11 05:39:06 DEBUG : Creating backend with remote "/tmp/rclone111874955" === RUN TestCheckDownload run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:39:10 DEBUG : rutabaga: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:39:12 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:39:17 DEBUG : remotepotato: Trying to read object after upload: try again in 100ms (1/10) === RUN TestCheckDownload/6 --- PASS: TestCheckDownload (18.36s) --- PASS: TestCheckDownload/6 (0.93s) === RUN TestCheckSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:39:24 DEBUG : rutabaga: Update: removing old file 2026/08/11 05:39:25 ERROR : rutabaga: Update: failed to remove existing file: could not Remove: Linkbox error 403: not permit 2026/08/11 05:39:26 DEBUG : rutabaga: Trying to read object after upload: try again in 100ms (1/10) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/08/11 05:39:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:39:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:39:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:39:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:39:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:41 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/08/11 05:39:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:39:45 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:39:49 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:39:49 DEBUG : pacer: Reducing sleep to 843.75ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/08/11 05:39:50 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:39:51 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestCheckSizeOnly (32.88s) === RUN TestApplyTransforms 2026/08/11 05:39:57 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-xeqayed8diwi" 2026/08/11 05:39:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:39:57 DEBUG : Linkbox root 'rclone-test-xeqayed8diwi': Using cached web token 2026/08/11 05:39:58 DEBUG : Creating backend with remote "/tmp/rclone854501682" run.go:198: Remote "Linkbox root 'rclone-test-xeqayed8diwi'", Local "Local file system at /tmp/rclone854501682", Modify Window "876000h0m0s" 2026/08/11 05:40:02 DEBUG : hello, world!: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:40:02 DEBUG : hello, world!: Trying to read object after upload: try again in 200ms (2/10) upper checkfile vs. lower remote (without normalization) 2026/08/11 05:40:03 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-xeqayed8diwi' 2026/08/11 05:40:03 NOTICE: Linkbox root 'rclone-test-xeqayed8diwi': 1 files missing 2026/08/11 05:40:03 NOTICE: Linkbox root 'rclone-test-xeqayed8diwi': 0 differences found 2026/08/11 05:40:03 NOTICE: Linkbox root 'rclone-test-xeqayed8diwi': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/08/11 05:40:03 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:03 NOTICE: Linkbox root 'rclone-test-xeqayed8diwi': 0 differences found 2026/08/11 05:40:03 NOTICE: Linkbox root 'rclone-test-xeqayed8diwi': 1 matching files 2026/08/11 05:40:03 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-nukihux0laho" 2026/08/11 05:40:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:03 DEBUG : Linkbox root 'rclone-test-nukihux0laho': Using cached web token 2026/08/11 05:40:04 DEBUG : Creating backend with remote "/tmp/rclone3377370369" run.go:198: Remote "Linkbox root 'rclone-test-nukihux0laho'", Local "Local file system at /tmp/rclone3377370369", Modify Window "876000h0m0s" 2026/08/11 05:40:08 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:40:08 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:40:09 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 400ms (3/10) lower checkfile vs. upper remote (without normalization) 2026/08/11 05:40:10 ERROR : HELLO, WORLD!: sum not found 2026/08/11 05:40:10 ERROR : hello, world!: file not in Linkbox root 'rclone-test-nukihux0laho' 2026/08/11 05:40:10 NOTICE: Linkbox root 'rclone-test-nukihux0laho': 1 files missing 2026/08/11 05:40:10 NOTICE: 1 hashes missing 2026/08/11 05:40:10 NOTICE: Linkbox root 'rclone-test-nukihux0laho': 1 differences found 2026/08/11 05:40:10 NOTICE: Linkbox root 'rclone-test-nukihux0laho': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/08/11 05:40:10 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:10 NOTICE: Linkbox root 'rclone-test-nukihux0laho': 0 differences found 2026/08/11 05:40:10 NOTICE: Linkbox root 'rclone-test-nukihux0laho': 1 matching files 2026/08/11 05:40:10 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-cocomos2judo" 2026/08/11 05:40:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:10 DEBUG : Linkbox root 'rclone-test-cocomos2judo': Using cached web token 2026/08/11 05:40:11 DEBUG : Creating backend with remote "/tmp/rclone3348526073" run.go:198: Remote "Linkbox root 'rclone-test-cocomos2judo'", Local "Local file system at /tmp/rclone3348526073", Modify Window "876000h0m0s" 2026/08/11 05:40:15 DEBUG : HeLlO, wOrLd!: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:40:15 DEBUG : HeLlO, wOrLd!: Trying to read object after upload: try again in 200ms (2/10) lower checkfile vs. upperlowermixed remote (without normalization) 2026/08/11 05:40:16 ERROR : HeLlO, wOrLd!: sum not found 2026/08/11 05:40:16 ERROR : hello, world!: file not in Linkbox root 'rclone-test-cocomos2judo' 2026/08/11 05:40:16 NOTICE: Linkbox root 'rclone-test-cocomos2judo': 1 files missing 2026/08/11 05:40:16 NOTICE: 1 hashes missing 2026/08/11 05:40:16 NOTICE: Linkbox root 'rclone-test-cocomos2judo': 1 differences found 2026/08/11 05:40:16 NOTICE: Linkbox root 'rclone-test-cocomos2judo': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/08/11 05:40:16 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:16 NOTICE: Linkbox root 'rclone-test-cocomos2judo': 0 differences found 2026/08/11 05:40:16 NOTICE: Linkbox root 'rclone-test-cocomos2judo': 1 matching files 2026/08/11 05:40:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-mucarux0naja" 2026/08/11 05:40:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:16 DEBUG : Linkbox root 'rclone-test-mucarux0naja': Using cached web token 2026/08/11 05:40:17 DEBUG : Creating backend with remote "/tmp/rclone3402183229" run.go:198: Remote "Linkbox root 'rclone-test-mucarux0naja'", Local "Local file system at /tmp/rclone3402183229", Modify Window "876000h0m0s" 2026/08/11 05:40:21 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:40:21 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:40:22 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 400ms (3/10) upperlowermixed checkfile vs. upper remote (without normalization) 2026/08/11 05:40:23 ERROR : HELLO, WORLD!: sum not found 2026/08/11 05:40:23 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-mucarux0naja' 2026/08/11 05:40:23 NOTICE: Linkbox root 'rclone-test-mucarux0naja': 1 files missing 2026/08/11 05:40:23 NOTICE: 1 hashes missing 2026/08/11 05:40:23 NOTICE: Linkbox root 'rclone-test-mucarux0naja': 1 differences found 2026/08/11 05:40:23 NOTICE: Linkbox root 'rclone-test-mucarux0naja': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/08/11 05:40:23 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:23 NOTICE: Linkbox root 'rclone-test-mucarux0naja': 0 differences found 2026/08/11 05:40:23 NOTICE: Linkbox root 'rclone-test-mucarux0naja': 1 matching files 2026/08/11 05:40:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-muzexon9giri" 2026/08/11 05:40:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:23 DEBUG : Linkbox root 'rclone-test-muzexon9giri': Using cached web token 2026/08/11 05:40:24 DEBUG : Creating backend with remote "/tmp/rclone2439715025" run.go:198: Remote "Linkbox root 'rclone-test-muzexon9giri'", Local "Local file system at /tmp/rclone2439715025", Modify Window "876000h0m0s" 2026/08/11 05:40:28 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:40:28 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:40:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:40:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:29 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:40:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:40:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:40:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) check_test.go:568: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:568 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:598 Error: Received unexpected error: getting files failed: invalid character '<' looking for beginning of value Test: TestApplyTransforms NFD checkfile vs. NFC remote (without normalization) 2026/08/11 05:40:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:40:46 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:40:46 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/11 05:40:46 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-muzexon9giri' 2026/08/11 05:40:46 NOTICE: Linkbox root 'rclone-test-muzexon9giri': 1 files missing 2026/08/11 05:40:46 NOTICE: 1 hashes missing 2026/08/11 05:40:46 NOTICE: Linkbox root 'rclone-test-muzexon9giri': 1 differences found 2026/08/11 05:40:46 NOTICE: Linkbox root 'rclone-test-muzexon9giri': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/08/11 05:40:48 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:40:49 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:40:49 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:49 NOTICE: Linkbox root 'rclone-test-muzexon9giri': 0 differences found 2026/08/11 05:40:49 NOTICE: Linkbox root 'rclone-test-muzexon9giri': 1 matching files 2026/08/11 05:40:49 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-qivitaz0qiko" 2026/08/11 05:40:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:49 DEBUG : Linkbox root 'rclone-test-qivitaz0qiko': Using cached web token 2026/08/11 05:40:50 DEBUG : Creating backend with remote "/tmp/rclone1810787022" run.go:198: Remote "Linkbox root 'rclone-test-qivitaz0qiko'", Local "Local file system at /tmp/rclone1810787022", Modify Window "876000h0m0s" 2026/08/11 05:40:54 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) NFC checkfile vs. NFD remote (without normalization) 2026/08/11 05:40:55 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/11 05:40:55 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-qivitaz0qiko' 2026/08/11 05:40:55 NOTICE: Linkbox root 'rclone-test-qivitaz0qiko': 1 files missing 2026/08/11 05:40:55 NOTICE: 1 hashes missing 2026/08/11 05:40:55 NOTICE: Linkbox root 'rclone-test-qivitaz0qiko': 1 differences found 2026/08/11 05:40:55 NOTICE: Linkbox root 'rclone-test-qivitaz0qiko': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/08/11 05:40:55 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:40:55 NOTICE: Linkbox root 'rclone-test-qivitaz0qiko': 0 differences found 2026/08/11 05:40:55 NOTICE: Linkbox root 'rclone-test-qivitaz0qiko': 1 matching files 2026/08/11 05:40:55 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vobihul4nube" 2026/08/11 05:40:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:40:55 DEBUG : Linkbox root 'rclone-test-vobihul4nube': Using cached web token 2026/08/11 05:40:56 DEBUG : Creating backend with remote "/tmp/rclone366667547" run.go:198: Remote "Linkbox root 'rclone-test-vobihul4nube'", Local "Local file system at /tmp/rclone366667547", Modify Window "876000h0m0s" 2026/08/11 05:41:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:41:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:41:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 400ms (3/10) NFDx2 checkfile vs. both remote (without normalization) 2026/08/11 05:41:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/11 05:41:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-vobihul4nube' 2026/08/11 05:41:02 NOTICE: Linkbox root 'rclone-test-vobihul4nube': 1 files missing 2026/08/11 05:41:02 NOTICE: 1 hashes missing 2026/08/11 05:41:02 NOTICE: Linkbox root 'rclone-test-vobihul4nube': 1 differences found 2026/08/11 05:41:02 NOTICE: Linkbox root 'rclone-test-vobihul4nube': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/08/11 05:41:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:41:02 NOTICE: Linkbox root 'rclone-test-vobihul4nube': 0 differences found 2026/08/11 05:41:02 NOTICE: Linkbox root 'rclone-test-vobihul4nube': 1 matching files 2026/08/11 05:41:02 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-segibuv4xehe" 2026/08/11 05:41:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:41:02 DEBUG : Linkbox root 'rclone-test-segibuv4xehe': Using cached web token 2026/08/11 05:41:03 DEBUG : Creating backend with remote "/tmp/rclone1633481677" run.go:198: Remote "Linkbox root 'rclone-test-segibuv4xehe'", Local "Local file system at /tmp/rclone1633481677", Modify Window "876000h0m0s" 2026/08/11 05:41:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:41:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) NFCx2 checkfile vs. both remote (without normalization) 2026/08/11 05:41:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-segibuv4xehe' 2026/08/11 05:41:08 NOTICE: Linkbox root 'rclone-test-segibuv4xehe': 1 files missing 2026/08/11 05:41:08 NOTICE: Linkbox root 'rclone-test-segibuv4xehe': 0 differences found 2026/08/11 05:41:08 NOTICE: Linkbox root 'rclone-test-segibuv4xehe': 1 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/08/11 05:41:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:41:08 NOTICE: Linkbox root 'rclone-test-segibuv4xehe': 0 differences found 2026/08/11 05:41:08 NOTICE: Linkbox root 'rclone-test-segibuv4xehe': 1 matching files 2026/08/11 05:41:08 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-renigod4liqe" 2026/08/11 05:41:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:41:08 DEBUG : Linkbox root 'rclone-test-renigod4liqe': Using cached web token 2026/08/11 05:41:09 DEBUG : Creating backend with remote "/tmp/rclone1269070033" run.go:198: Remote "Linkbox root 'rclone-test-renigod4liqe'", Local "Local file system at /tmp/rclone1269070033", Modify Window "876000h0m0s" 2026/08/11 05:41:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:41:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) both checkfile vs. NFDx2 remote (without normalization) 2026/08/11 05:41:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/11 05:41:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-renigod4liqe' 2026/08/11 05:41:14 NOTICE: Linkbox root 'rclone-test-renigod4liqe': 1 files missing 2026/08/11 05:41:14 NOTICE: 1 hashes missing 2026/08/11 05:41:14 NOTICE: Linkbox root 'rclone-test-renigod4liqe': 1 differences found 2026/08/11 05:41:14 NOTICE: Linkbox root 'rclone-test-renigod4liqe': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/08/11 05:41:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:41:14 NOTICE: Linkbox root 'rclone-test-renigod4liqe': 0 differences found 2026/08/11 05:41:14 NOTICE: Linkbox root 'rclone-test-renigod4liqe': 1 matching files 2026/08/11 05:41:14 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-hepaqoc1lucu" 2026/08/11 05:41:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/11 05:41:14 DEBUG : Linkbox root 'rclone-test-hepaqoc1lucu': Using cached web token 2026/08/11 05:41:15 DEBUG : Creating backend with remote "/tmp/rclone1476738052" run.go:198: Remote "Linkbox root 'rclone-test-hepaqoc1lucu'", Local "Local file system at /tmp/rclone1476738052", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/08/11 05:41:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/11 05:41:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-hepaqoc1lucu' 2026/08/11 05:41:19 NOTICE: Linkbox root 'rclone-test-hepaqoc1lucu': 1 files missing 2026/08/11 05:41:19 NOTICE: 1 hashes missing 2026/08/11 05:41:19 NOTICE: Linkbox root 'rclone-test-hepaqoc1lucu': 1 differences found 2026/08/11 05:41:19 NOTICE: Linkbox root 'rclone-test-hepaqoc1lucu': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/08/11 05:41:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/11 05:41:20 NOTICE: Linkbox root 'rclone-test-hepaqoc1lucu': 0 differences found 2026/08/11 05:41:20 NOTICE: Linkbox root 'rclone-test-hepaqoc1lucu': 1 matching files 2026/08/11 05:41:20 DEBUG : Linkbox root 'rclone-test-hepaqoc1lucu': Purge remote 2026/08/11 05:41:21 DEBUG : Linkbox root 'rclone-test-renigod4liqe': Purge remote 2026/08/11 05:41:23 DEBUG : Linkbox root 'rclone-test-segibuv4xehe': Purge remote 2026/08/11 05:41:24 DEBUG : Linkbox root 'rclone-test-vobihul4nube': Purge remote 2026/08/11 05:41:25 DEBUG : Linkbox root 'rclone-test-qivitaz0qiko': Purge remote 2026/08/11 05:41:27 DEBUG : Linkbox root 'rclone-test-muzexon9giri': Purge remote 2026/08/11 05:41:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2026/08/11 05:41:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:41:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:39 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:41 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:43 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:43 NOTICE: purge failed: purge error: invalid character '<' looking for beginning of value 2026/08/11 05:41:43 DEBUG : Linkbox root 'rclone-test-mucarux0naja': Purge remote 2026/08/11 05:41:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:41:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:43 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:41:44 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:44 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:41:45 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:41:45 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:41:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:41:47 DEBUG : Linkbox root 'rclone-test-cocomos2judo': Purge remote 2026/08/11 05:41:49 DEBUG : Linkbox root 'rclone-test-nukihux0laho': Purge remote 2026/08/11 05:41:50 DEBUG : Linkbox root 'rclone-test-xeqayed8diwi': Purge remote --- FAIL: TestApplyTransforms (114.62s) === RUN TestCopyFileCompareDest run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:41:52 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vodihir0fori/dst" 2026/08/11 05:41:52 DEBUG : Linkbox root 'rclone-test-vodihir0fori/dst': Using cached web token 2026/08/11 05:41:53 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vodihir0fori/CompareDest" 2026/08/11 05:41:53 DEBUG : Linkbox root 'rclone-test-vodihir0fori/CompareDest': Using cached web token 2026/08/11 05:41:54 DEBUG : one: Need to transfer - File not found at Destination 2026/08/11 05:41:58 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:41:58 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:41:59 DEBUG : one: size = 3 OK 2026/08/11 05:41:59 INFO : one: Copied (new) 2026/08/11 05:41:59 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:41:59 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:42:00 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:42:00 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:42:00 DEBUG : one: size = 5 (Local file system at /tmp/rclone111874955) 2026/08/11 05:42:00 DEBUG : one: size = 3 (Linkbox root 'rclone-test-vodihir0fori/dst') 2026/08/11 05:42:00 DEBUG : one: Sizes differ 2026/08/11 05:42:01 DEBUG : one: Update: removing old file 2026/08/11 05:42:04 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:42:04 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:42:04 DEBUG : one: Trying to read object after upload: try again in 400ms (3/10) 2026/08/11 05:42:05 DEBUG : one: size = 5 OK 2026/08/11 05:42:05 INFO : one: Copied (replaced existing) 2026/08/11 05:42:06 DEBUG : dst/one: Update: removing old file 2026/08/11 05:42:09 DEBUG : dst/one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:42:09 DEBUG : dst/one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:42:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone111874955) 2026/08/11 05:42:14 DEBUG : one: size = 3 (Linkbox root 'rclone-test-vodihir0fori/dst') 2026/08/11 05:42:14 DEBUG : one: Sizes differ 2026/08/11 05:42:15 DEBUG : one: size = 5 OK 2026/08/11 05:42:15 DEBUG : one: Sizes identical 2026/08/11 05:42:15 DEBUG : one: Destination found in --compare-dest, skipping 2026/08/11 05:42:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:17 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:42:18 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:18 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:42:18 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:18 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:42:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:42:20 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:22 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:24 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:26 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:28 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:30 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:32 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:39 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:41 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:43 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:45 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:42:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:42:49 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:42:49 DEBUG : two: Need to transfer - File not found at Destination 2026/08/11 05:42:49 DEBUG : two: size = 3 OK 2026/08/11 05:42:49 DEBUG : two: Sizes identical 2026/08/11 05:42:49 DEBUG : two: Destination found in --compare-dest, skipping 2026/08/11 05:42:50 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:42:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:42:52 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:42:52 DEBUG : two: Need to transfer - File not found at Destination 2026/08/11 05:42:53 DEBUG : two: size = 3 OK 2026/08/11 05:42:53 DEBUG : two: Sizes identical 2026/08/11 05:42:53 DEBUG : two: Destination found in --compare-dest, skipping 2026/08/11 05:42:53 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:42:53 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:42:54 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:42:54 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:42:54 DEBUG : two: Need to transfer - File not found at Destination 2026/08/11 05:42:55 DEBUG : two: size = 5 (Local file system at /tmp/rclone111874955) 2026/08/11 05:42:55 DEBUG : two: size = 3 (Linkbox root 'rclone-test-vodihir0fori/CompareDest') 2026/08/11 05:42:55 DEBUG : two: Sizes differ 2026/08/11 05:42:57 DEBUG : two: size = 5 OK 2026/08/11 05:42:57 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (74.77s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.40s) === RUN TestDeduplicateFirst run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.49s) === RUN TestListJSON run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:43:13 DEBUG : sub/file2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:43:13 DEBUG : sub/file2: Trying to read object after upload: try again in 200ms (2/10) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestListJSON/Default === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/Metadata === NAME TestListJSON run.go:186: removing dir "sub" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestListJSON (21.78s) --- PASS: TestListJSON/Default (0.21s) --- PASS: TestListJSON/DirsOnly (0.20s) --- PASS: TestListJSON/Recurse (0.41s) --- PASS: TestListJSON/Metadata (0.31s) === RUN TestLsWithFilesFrom run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:43:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:43:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:43:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:43:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:43:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:36 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:38 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:40 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:42 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-vodihir0fori': 1/10 (Update err in unmarshaling response: invalid character '<' looking for beginning of value) 2026/08/11 05:43:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:43:49 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:43:51 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:43:53 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:43:53 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:43:54 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:43:55 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:43:57 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:43:57 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:43:57 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:43:57 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/08/11 05:43:57 DEBUG : empty space: Excluded fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestLsWithFilesFrom (39.56s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:44:15 DEBUG : Waiting for deletions to finish 2026/08/11 05:44:15 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/08/11 05:44:17 INFO : medium: Deleted 2026/08/11 05:44:17 INFO : small: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestMaxDeleteSizeLargeFile (18.10s) === RUN TestCat run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:44:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:44:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:31 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:44:31 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:31 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:44:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:44:34 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:36 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:38 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:40 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:42 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:44 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:44 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/08/11 05:44:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:44:48 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:44:51 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:44:53 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:44:53 DEBUG : pacer: Reducing sleep to 632.8125ms --- FAIL: TestCat (26.12s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:44:54 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:44:55 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:44:56 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:44:57 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:44:57 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:45:01 INFO : A2: Making directory 2026/08/11 05:45:02 INFO : A1/B2: Making directory 2026/08/11 05:45:04 INFO : A1/B2/C2: Making directory 2026/08/11 05:45:05 INFO : A1/B1/C3: Making directory 2026/08/11 05:45:07 INFO : A3: Making directory 2026/08/11 05:45:08 INFO : A3/B3: Making directory 2026/08/11 05:45:10 INFO : A3/B3/C4: Making directory 2026/08/11 05:45:14 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:45:14 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:45:17 DEBUG : removing 1 level 3 directories 2026/08/11 05:45:17 INFO : A3/B3/C4: Removing directory 2026/08/11 05:45:22 DEBUG : removing 2 level 3 directories 2026/08/11 05:45:22 INFO : A1/B2/C2: Removing directory 2026/08/11 05:45:22 INFO : A1/B1/C3: Removing directory 2026/08/11 05:45:24 DEBUG : removing 2 level 2 directories 2026/08/11 05:45:24 INFO : A3/B3: Removing directory 2026/08/11 05:45:24 INFO : A1/B2: Removing directory 2026/08/11 05:45:26 DEBUG : removing 2 level 1 directories 2026/08/11 05:45:26 INFO : A3: Removing directory 2026/08/11 05:45:26 INFO : A2: Removing directory 2026/08/11 05:45:33 DEBUG : removing 1 level 3 directories 2026/08/11 05:45:33 INFO : A1/B1/C1: Removing directory 2026/08/11 05:45:34 DEBUG : removing 1 level 2 directories 2026/08/11 05:45:34 INFO : A1/B1: Removing directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:730 Error: Not equal: expected: []string{} actual : []string{"A1"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=2) "A1" } Test: TestRmdirsNoLeaveRoot Messages: directories --- FAIL: TestRmdirsNoLeaveRoot (54.65s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:45:48 INFO : A1: Making directory 2026/08/11 05:45:49 INFO : A1/B1: Making directory 2026/08/11 05:45:51 INFO : A1/B1/C1: Making directory 2026/08/11 05:45:54 DEBUG : removing 1 level 3 directories 2026/08/11 05:45:54 INFO : A1/B1/C1: Removing directory 2026/08/11 05:45:55 DEBUG : removing 1 level 2 directories 2026/08/11 05:45:55 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (11.87s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:46:02 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (4.00s) === RUN TestMoveFile run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:46:04 DEBUG : file1: Need to transfer - File not found at Destination 2026/08/11 05:46:07 DEBUG : sub/file2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:46:08 DEBUG : sub/file2: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:46:08 DEBUG : sub/file2: size = 14 OK 2026/08/11 05:46:08 INFO : file1: Copied (new) to: sub/file2 2026/08/11 05:46:08 INFO : file1: Deleted 2026/08/11 05:46:09 DEBUG : sub/file2: size = 14 OK 2026/08/11 05:46:09 DEBUG : file1: Sizes identical 2026/08/11 05:46:09 DEBUG : file1: Unchanged skipping 2026/08/11 05:46:09 INFO : file1: Deleted 2026/08/11 05:46:09 DEBUG : Linkbox root 'rclone-test-vodihir0fori': don't need to copy/move sub/file2, it is already at target location run.go:186: removing dir "sub" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestMoveFile (10.81s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:46:14 DEBUG : file1: Need to transfer - File not found at Destination 2026/08/11 05:46:16 DEBUG : file1: size = 14 OK 2026/08/11 05:46:16 INFO : file1: Copied (new) 2026/08/11 05:46:16 INFO : file1: Deleted 2026/08/11 05:46:17 DEBUG : file1: Destination exists, skipping 2026/08/11 05:46:17 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestMoveFileWithIgnoreExisting (12.28s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:46:28 DEBUG : hello: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:46:29 NOTICE: hello: Skipped rename to HELLO as --dry-run is set fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestCaseInsensitiveMoveFileDryRun (12.48s) === RUN TestMoveFileBackupDir run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1063: Skipping test as remote does not support server-side move or copy --- SKIP: TestMoveFileBackupDir (0.40s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:46:43 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:46:45 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:46:46 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:46:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:46:46 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:46:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:46:46 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:46:46 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:46:46 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:46:47 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:46:47 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:46:49 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:46:51 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:46:53 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:46:56 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:46:56 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:46:57 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:46:59 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:46:59 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:46:59 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:47:00 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:47:02 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:47:03 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:47:03 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10) 2026/08/11 05:47:07 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:47:08 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:47:08 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10) 2026/08/11 05:47:09 INFO : A1/B2: Making directory 2026/08/11 05:47:10 INFO : A1/B1/C3: Making directory 2026/08/11 05:47:29 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:47:29 DEBUG : A2/one: size = 3 OK 2026/08/11 05:47:29 INFO : A1/one: Copied (new) to: A2/one 2026/08/11 05:47:29 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/08/11 05:47:30 DEBUG : A2/two: size = 3 OK 2026/08/11 05:47:30 INFO : A1/two: Copied (new) to: A2/two 2026/08/11 05:47:30 DEBUG : A2/B1/C1/four: size = 4 OK 2026/08/11 05:47:30 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/08/11 05:47:30 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/08/11 05:47:30 DEBUG : A2/B1/C2/five: size = 4 OK 2026/08/11 05:47:30 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/08/11 05:47:31 INFO : A1/one: Deleted 2026/08/11 05:47:31 DEBUG : A2/B1/three: size = 5 OK 2026/08/11 05:47:31 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/08/11 05:47:32 INFO : A1/two: Deleted 2026/08/11 05:47:32 INFO : A1/B1/C1/four: Deleted 2026/08/11 05:47:32 INFO : A1/B1/C2/five: Deleted 2026/08/11 05:47:33 INFO : A1/B1/three: Deleted 2026/08/11 05:47:49 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:47:49 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:47:49 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:47:49 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:47:50 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:47:50 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:47:50 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:47:50 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:47:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:47:55 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:47:56 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:47:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:47:58 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:48:00 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:48:00 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:48:00 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:48:01 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:48:06 DEBUG : A3/one: size = 3 OK 2026/08/11 05:48:06 INFO : A2/one: Copied (new) to: A3/one 2026/08/11 05:48:06 DEBUG : A3/two: size = 3 OK 2026/08/11 05:48:06 INFO : A2/two: Copied (new) to: A3/two 2026/08/11 05:48:06 DEBUG : A3/B1/three: size = 5 OK 2026/08/11 05:48:06 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/08/11 05:48:07 DEBUG : A3/B1/C1/four: size = 4 OK 2026/08/11 05:48:07 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/08/11 05:48:07 DEBUG : A3/B1/C2/five: size = 4 OK 2026/08/11 05:48:07 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/08/11 05:48:07 INFO : A2/one: Deleted 2026/08/11 05:48:08 INFO : A2/two: Deleted 2026/08/11 05:48:08 INFO : A2/B1/three: Deleted 2026/08/11 05:48:09 INFO : A2/B1/C1/four: Deleted 2026/08/11 05:48:09 INFO : A2/B1/C2/five: Deleted 2026/08/11 05:48:19 INFO : Linkbox root 'rclone-test-vodihir0fori': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/08/11 05:48:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:33 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:48:33 DEBUG : pacer: Reducing sleep to 300ms 2026/08/11 05:48:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:33 DEBUG : pacer: Rate limited, increasing sleep to 600ms 2026/08/11 05:48:34 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:34 DEBUG : pacer: Rate limited, increasing sleep to 1.2s 2026/08/11 05:48:34 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:34 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:48:36 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:38 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:40 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:42 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:44 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:46 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:50 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:52 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:48:55 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:48:56 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:48:58 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:48:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:49:00 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:49:00 DEBUG : A4/B1/three: size = 5 OK 2026/08/11 05:49:00 INFO : A3/B1/three: Copied (new) to: A4/B1/three 2026/08/11 05:49:01 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:49:02 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:49:02 DEBUG : A4/B1/C2/five: size = 4 OK 2026/08/11 05:49:02 INFO : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five 2026/08/11 05:49:02 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:49:02 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:49:02 DEBUG : A4/B1/C1/four: size = 4 OK 2026/08/11 05:49:02 INFO : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four 2026/08/11 05:49:03 DEBUG : A4/two: size = 3 OK 2026/08/11 05:49:03 INFO : A3/two: Copied (new) to: A4/two 2026/08/11 05:49:03 DEBUG : A4/one: size = 3 OK 2026/08/11 05:49:03 INFO : A3/one: Copied (new) to: A4/one 2026/08/11 05:49:03 INFO : A3/B1/three: Deleted 2026/08/11 05:49:03 INFO : A3/B1/C2/five: Deleted 2026/08/11 05:49:04 INFO : A3/B1/C1/four: Deleted 2026/08/11 05:49:04 INFO : A3/two: Deleted 2026/08/11 05:49:04 INFO : A3/one: Deleted 2026/08/11 05:49:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:49:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:49:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:49:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:49:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:36 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:38 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:40 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:42 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:186: removing dir "A4/B1" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value 2026/08/11 05:49:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:50 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:49:53 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:49:56 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/11 05:49:56 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:49:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/11 05:49:58 DEBUG : pacer: Reducing sleep to 474.609375ms --- PASS: TestDirMove (199.11s) === RUN TestGetFsInfo run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:49:58 DEBUG : Config file has changed externally - reloading 2026/08/11 05:49:59 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:50:00 DEBUG : pacer: Reducing sleep to 266.967773ms --- PASS: TestGetFsInfo (1.13s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:50:00 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:50:00 DEBUG : Linkbox root 'rclone-test-vodihir0fori': File to upload is small (34 bytes), uploading instead of streaming 2026/08/11 05:50:00 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:50:02 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/08/11 05:50:02 NOTICE: Linkbox root 'rclone-test-vodihir0fori': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/08/11 05:50:02 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/08/11 05:50:02 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/08/11 05:50:04 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/08/11 05:50:04 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:50:08 DEBUG : Linkbox root 'rclone-test-vodihir0fori': File to upload is small (34 bytes), uploading instead of streaming 2026/08/11 05:50:10 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/08/11 05:50:10 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/08/11 05:50:10 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/08/11 05:50:12 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/08/11 05:50:12 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:50:15 DEBUG : Linkbox root 'rclone-test-vodihir0fori': File to upload is small (34 bytes), uploading instead of streaming 2026/08/11 05:50:17 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/08/11 05:50:17 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical 2026/08/11 05:50:17 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/08/11 05:50:19 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/08/11 05:50:19 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:50:23 DEBUG : Linkbox root 'rclone-test-vodihir0fori': File to upload is small (34 bytes), uploading instead of streaming 2026/08/11 05:50:25 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/08/11 05:50:25 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/08/11 05:50:25 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/08/11 05:50:27 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/08/11 05:50:27 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical 2026/08/11 05:50:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:50:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:29 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/11 05:50:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/11 05:50:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/11 05:50:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:172: removing file "ignore_checksum_small_file_from_pipe" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value 2026/08/11 05:50:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:51 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/11 05:50:55 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/11 05:50:56 DEBUG : pacer: Reducing sleep to 1.125s --- PASS: TestRcat (55.90s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.76s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.47s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.48s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (33.19s) === RUN TestRcatMetadata run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1563: Skipping as destination doesn't support user metadata 2026/08/11 05:50:57 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/11 05:50:58 DEBUG : pacer: Reducing sleep to 632.8125ms --- SKIP: TestRcatMetadata (2.65s) === RUN TestRcatSize run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:50:59 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/11 05:51:00 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/11 05:51:01 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/11 05:51:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/11 05:51:01 DEBUG : potato1: size = 60 OK 2026/08/11 05:51:01 DEBUG : Linkbox root 'rclone-test-vodihir0fori': File to upload is small (60 bytes), uploading instead of streaming 2026/08/11 05:51:02 DEBUG : pacer: Reducing sleep to 200ms 2026/08/11 05:51:04 DEBUG : potato2: size = 60 OK 2026/08/11 05:51:04 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (8.93s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:51:08 DEBUG : pacer: low level retry 1/1 (error Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1786434667&Signature=xzvwOvAuAhLv5fRoesbYiOoi64A%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer) 2026/08/11 05:51:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/11 05:51:08 ERROR : potato1: Post request put error: update err in uploading file: Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1786434667&Signature=xzvwOvAuAhLv5fRoesbYiOoi64A%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer 2026/08/11 05:51:08 DEBUG : pacer: Reducing sleep to 300ms 2026/08/11 05:51:09 DEBUG : pacer: Reducing sleep to 225ms --- PASS: TestRcatSizeShortEOF (1.50s) === RUN TestRcatSizeMetadata run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1656: Skipping as destination doesn't support user metadata 2026/08/11 05:51:09 DEBUG : pacer: Reducing sleep to 200ms --- SKIP: TestRcatSizeMetadata (0.54s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" 2026/08/11 05:51:11 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (3.71s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1747: Skipping test as remote does not support modtime --- SKIP: TestTouchDir (0.40s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1785: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.40s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1804: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.42s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1825: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.41s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1861: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.56s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1905: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.41s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-vodihir0fori'", Local "Local file system at /tmp/rclone111874955", Modify Window "876000h0m0s" operations_test.go:1974: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.41s) FAIL 2026/08/11 05:51:16 DEBUG : Linkbox root 'rclone-test-vodihir0fori': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSizeOnly|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURLDownloadHeaders|TestDeduplicateFirst|TestDeduplicateSizeOnly|TestDirMove|TestDirsEqual|TestGetFsInfo|TestLsWithFilesFrom|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestSetDirModTime|TestTouchDir)$|^TestCheckDownload$/^6$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 12m12.404755536s (try 2/5): exit status 1: Failed [TestCheckSizeOnly TestApplyTransforms TestCat TestRmdirsNoLeaveRoot]