"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSizeOnly|TestCheckSumDownload|TestCopyDirMetadata|TestCopyInplace|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDeleteFatalError|TestDirMove|TestDirMoveContext|TestDirMoveMoveError|TestDirsEqual|TestGetFsInfo|TestHashSums|TestLs|TestLsLong|TestLsWithFilesFrom|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileImmutable|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestRcatSizeChecksum$/^(Corrupted|IgnoreChecksum|NoHashes|SizeDiffers)$'" - Starting (try 2/5) 2026/09/27 02:19:29 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tiqemix3lovi" 2026/09/27 02:19:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:19:29 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Using cached web token 2026/09/27 02:19:30 DEBUG : Creating backend with remote "/tmp/rclone3409351160" === RUN TestCheckSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (18.41s) --- PASS: TestCheckSizeOnly/1 (0.20s) --- PASS: TestCheckSizeOnly/2 (0.20s) --- PASS: TestCheckSizeOnly/3 (0.20s) --- PASS: TestCheckSizeOnly/4 (0.20s) --- PASS: TestCheckSizeOnly/5 (0.22s) --- PASS: TestCheckSizeOnly/6 (0.20s) --- PASS: TestCheckSizeOnly/7 (0.20s) === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:19:49 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tiqemix3lovi/data" 2026/09/27 02:19:49 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi/data': Using cached web token === RUN TestCheckSumDownload/subtest1 2026/09/27 02:19:59 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest2 2026/09/27 02:20:04 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest3 2026/09/27 02:20:08 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest4 2026/09/27 02:20:13 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest5 2026/09/27 02:20:17 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest6 2026/09/27 02:20:22 DEBUG : data/banana: Update: removing old file 2026/09/27 02:20:25 DEBUG : data/potato: Update: removing old file 2026/09/27 02:20:29 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (50.32s) --- PASS: TestCheckSumDownload/subtest1 (0.95s) --- PASS: TestCheckSumDownload/subtest2 (0.60s) --- PASS: TestCheckSumDownload/subtest3 (0.67s) --- PASS: TestCheckSumDownload/subtest4 (0.70s) --- PASS: TestCheckSumDownload/subtest5 (0.67s) --- PASS: TestCheckSumDownload/subtest6 (0.67s) --- PASS: TestCheckSumDownload/subtest7 (0.68s) === RUN TestApplyTransforms 2026/09/27 02:20:39 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-roriput7dure" 2026/09/27 02:20:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:20:39 DEBUG : Linkbox root 'rclone-test-roriput7dure': Using cached web token 2026/09/27 02:20:40 DEBUG : Creating backend with remote "/tmp/rclone2060740472" run.go:198: Remote "Linkbox root 'rclone-test-roriput7dure'", Local "Local file system at /tmp/rclone2060740472", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/09/27 02:20:44 ERROR : hello, world!: sum not found 2026/09/27 02:20:44 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-roriput7dure' 2026/09/27 02:20:44 NOTICE: Linkbox root 'rclone-test-roriput7dure': 1 files missing 2026/09/27 02:20:44 NOTICE: 1 hashes missing 2026/09/27 02:20:44 NOTICE: Linkbox root 'rclone-test-roriput7dure': 1 differences found 2026/09/27 02:20:44 NOTICE: Linkbox root 'rclone-test-roriput7dure': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/27 02:20:44 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:20:44 NOTICE: Linkbox root 'rclone-test-roriput7dure': 0 differences found 2026/09/27 02:20:44 NOTICE: Linkbox root 'rclone-test-roriput7dure': 1 matching files 2026/09/27 02:20:44 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-gekuqul9hebe" 2026/09/27 02:20:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:20:44 DEBUG : Linkbox root 'rclone-test-gekuqul9hebe': Using cached web token 2026/09/27 02:20:45 DEBUG : Creating backend with remote "/tmp/rclone1037663552" run.go:198: Remote "Linkbox root 'rclone-test-gekuqul9hebe'", Local "Local file system at /tmp/rclone1037663552", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/09/27 02:20:49 ERROR : HELLO, WORLD!: sum not found 2026/09/27 02:20:49 ERROR : hello, world!: file not in Linkbox root 'rclone-test-gekuqul9hebe' 2026/09/27 02:20:49 NOTICE: Linkbox root 'rclone-test-gekuqul9hebe': 1 files missing 2026/09/27 02:20:49 NOTICE: 1 hashes missing 2026/09/27 02:20:49 NOTICE: Linkbox root 'rclone-test-gekuqul9hebe': 1 differences found 2026/09/27 02:20:49 NOTICE: Linkbox root 'rclone-test-gekuqul9hebe': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/27 02:20:50 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:20:50 NOTICE: Linkbox root 'rclone-test-gekuqul9hebe': 0 differences found 2026/09/27 02:20:50 NOTICE: Linkbox root 'rclone-test-gekuqul9hebe': 1 matching files 2026/09/27 02:20:50 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pecucil8qopa" 2026/09/27 02:20:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:20:50 DEBUG : Linkbox root 'rclone-test-pecucil8qopa': Using cached web token 2026/09/27 02:20:51 DEBUG : Creating backend with remote "/tmp/rclone2494811475" run.go:198: Remote "Linkbox root 'rclone-test-pecucil8qopa'", Local "Local file system at /tmp/rclone2494811475", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/27 02:20:55 ERROR : HeLlO, wOrLd!: sum not found 2026/09/27 02:20:55 ERROR : hello, world!: file not in Linkbox root 'rclone-test-pecucil8qopa' 2026/09/27 02:20:55 NOTICE: Linkbox root 'rclone-test-pecucil8qopa': 1 files missing 2026/09/27 02:20:55 NOTICE: 1 hashes missing 2026/09/27 02:20:55 NOTICE: Linkbox root 'rclone-test-pecucil8qopa': 1 differences found 2026/09/27 02:20:55 NOTICE: Linkbox root 'rclone-test-pecucil8qopa': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/27 02:20:55 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:20:55 NOTICE: Linkbox root 'rclone-test-pecucil8qopa': 0 differences found 2026/09/27 02:20:55 NOTICE: Linkbox root 'rclone-test-pecucil8qopa': 1 matching files 2026/09/27 02:20:55 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kubuluh8mavi" 2026/09/27 02:20:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:20:55 DEBUG : Linkbox root 'rclone-test-kubuluh8mavi': Using cached web token 2026/09/27 02:20:56 DEBUG : Creating backend with remote "/tmp/rclone2725615113" run.go:198: Remote "Linkbox root 'rclone-test-kubuluh8mavi'", Local "Local file system at /tmp/rclone2725615113", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/27 02:21:00 ERROR : HELLO, WORLD!: sum not found 2026/09/27 02:21:00 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-kubuluh8mavi' 2026/09/27 02:21:00 NOTICE: Linkbox root 'rclone-test-kubuluh8mavi': 1 files missing 2026/09/27 02:21:00 NOTICE: 1 hashes missing 2026/09/27 02:21:00 NOTICE: Linkbox root 'rclone-test-kubuluh8mavi': 1 differences found 2026/09/27 02:21:00 NOTICE: Linkbox root 'rclone-test-kubuluh8mavi': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/27 02:21:00 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:00 NOTICE: Linkbox root 'rclone-test-kubuluh8mavi': 0 differences found 2026/09/27 02:21:00 NOTICE: Linkbox root 'rclone-test-kubuluh8mavi': 1 matching files 2026/09/27 02:21:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-caduxac2siyi" 2026/09/27 02:21:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:00 DEBUG : Linkbox root 'rclone-test-caduxac2siyi': Using cached web token 2026/09/27 02:21:01 DEBUG : Creating backend with remote "/tmp/rclone2590414362" run.go:198: Remote "Linkbox root 'rclone-test-caduxac2siyi'", Local "Local file system at /tmp/rclone2590414362", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/09/27 02:21:05 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:05 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-caduxac2siyi' 2026/09/27 02:21:05 NOTICE: Linkbox root 'rclone-test-caduxac2siyi': 1 files missing 2026/09/27 02:21:05 NOTICE: 1 hashes missing 2026/09/27 02:21:05 NOTICE: Linkbox root 'rclone-test-caduxac2siyi': 1 differences found 2026/09/27 02:21:05 NOTICE: Linkbox root 'rclone-test-caduxac2siyi': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/27 02:21:06 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:06 NOTICE: Linkbox root 'rclone-test-caduxac2siyi': 0 differences found 2026/09/27 02:21:06 NOTICE: Linkbox root 'rclone-test-caduxac2siyi': 1 matching files 2026/09/27 02:21:06 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dezevak7soha" 2026/09/27 02:21:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:06 DEBUG : Linkbox root 'rclone-test-dezevak7soha': Using cached web token 2026/09/27 02:21:07 DEBUG : Creating backend with remote "/tmp/rclone2224271604" run.go:198: Remote "Linkbox root 'rclone-test-dezevak7soha'", Local "Local file system at /tmp/rclone2224271604", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/09/27 02:21:11 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:11 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-dezevak7soha' 2026/09/27 02:21:11 NOTICE: Linkbox root 'rclone-test-dezevak7soha': 1 files missing 2026/09/27 02:21:11 NOTICE: 1 hashes missing 2026/09/27 02:21:11 NOTICE: Linkbox root 'rclone-test-dezevak7soha': 1 differences found 2026/09/27 02:21:11 NOTICE: Linkbox root 'rclone-test-dezevak7soha': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/27 02:21:11 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:11 NOTICE: Linkbox root 'rclone-test-dezevak7soha': 0 differences found 2026/09/27 02:21:11 NOTICE: Linkbox root 'rclone-test-dezevak7soha': 1 matching files 2026/09/27 02:21:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-gipucof7yezu" 2026/09/27 02:21:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:11 DEBUG : Linkbox root 'rclone-test-gipucof7yezu': Using cached web token 2026/09/27 02:21:12 DEBUG : Creating backend with remote "/tmp/rclone803311646" run.go:198: Remote "Linkbox root 'rclone-test-gipucof7yezu'", Local "Local file system at /tmp/rclone803311646", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/09/27 02:21:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-gipucof7yezu' 2026/09/27 02:21:16 NOTICE: Linkbox root 'rclone-test-gipucof7yezu': 1 files missing 2026/09/27 02:21:16 NOTICE: 1 hashes missing 2026/09/27 02:21:16 NOTICE: Linkbox root 'rclone-test-gipucof7yezu': 1 differences found 2026/09/27 02:21:16 NOTICE: Linkbox root 'rclone-test-gipucof7yezu': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/27 02:21:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:17 NOTICE: Linkbox root 'rclone-test-gipucof7yezu': 0 differences found 2026/09/27 02:21:17 NOTICE: Linkbox root 'rclone-test-gipucof7yezu': 1 matching files 2026/09/27 02:21:17 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vaviziy9deta" 2026/09/27 02:21:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:17 DEBUG : Linkbox root 'rclone-test-vaviziy9deta': Using cached web token 2026/09/27 02:21:18 DEBUG : Creating backend with remote "/tmp/rclone2796964357" run.go:198: Remote "Linkbox root 'rclone-test-vaviziy9deta'", Local "Local file system at /tmp/rclone2796964357", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/09/27 02:21:22 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:22 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-vaviziy9deta' 2026/09/27 02:21:22 NOTICE: Linkbox root 'rclone-test-vaviziy9deta': 1 files missing 2026/09/27 02:21:22 NOTICE: 1 hashes missing 2026/09/27 02:21:22 NOTICE: Linkbox root 'rclone-test-vaviziy9deta': 1 differences found 2026/09/27 02:21:22 NOTICE: Linkbox root 'rclone-test-vaviziy9deta': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/27 02:21:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:22 NOTICE: Linkbox root 'rclone-test-vaviziy9deta': 0 differences found 2026/09/27 02:21:22 NOTICE: Linkbox root 'rclone-test-vaviziy9deta': 1 matching files 2026/09/27 02:21:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-situgix0meco" 2026/09/27 02:21:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:22 DEBUG : Linkbox root 'rclone-test-situgix0meco': Using cached web token 2026/09/27 02:21:23 DEBUG : Creating backend with remote "/tmp/rclone2319514150" run.go:198: Remote "Linkbox root 'rclone-test-situgix0meco'", Local "Local file system at /tmp/rclone2319514150", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/09/27 02:21:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-situgix0meco' 2026/09/27 02:21:27 NOTICE: Linkbox root 'rclone-test-situgix0meco': 1 files missing 2026/09/27 02:21:27 NOTICE: 1 hashes missing 2026/09/27 02:21:27 NOTICE: Linkbox root 'rclone-test-situgix0meco': 1 differences found 2026/09/27 02:21:27 NOTICE: Linkbox root 'rclone-test-situgix0meco': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/27 02:21:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:28 NOTICE: Linkbox root 'rclone-test-situgix0meco': 0 differences found 2026/09/27 02:21:28 NOTICE: Linkbox root 'rclone-test-situgix0meco': 1 matching files 2026/09/27 02:21:28 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kijitik7mino" 2026/09/27 02:21:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/27 02:21:28 DEBUG : Linkbox root 'rclone-test-kijitik7mino': Using cached web token 2026/09/27 02:21:28 DEBUG : Creating backend with remote "/tmp/rclone2223678103" run.go:198: Remote "Linkbox root 'rclone-test-kijitik7mino'", Local "Local file system at /tmp/rclone2223678103", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/09/27 02:21:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/27 02:21:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-kijitik7mino' 2026/09/27 02:21:33 NOTICE: Linkbox root 'rclone-test-kijitik7mino': 1 files missing 2026/09/27 02:21:33 NOTICE: 1 hashes missing 2026/09/27 02:21:33 NOTICE: Linkbox root 'rclone-test-kijitik7mino': 1 differences found 2026/09/27 02:21:33 NOTICE: Linkbox root 'rclone-test-kijitik7mino': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/27 02:21:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/27 02:21:33 NOTICE: Linkbox root 'rclone-test-kijitik7mino': 0 differences found 2026/09/27 02:21:33 NOTICE: Linkbox root 'rclone-test-kijitik7mino': 1 matching files 2026/09/27 02:21:33 DEBUG : Linkbox root 'rclone-test-kijitik7mino': Purge remote 2026/09/27 02:21:34 DEBUG : Linkbox root 'rclone-test-situgix0meco': Purge remote 2026/09/27 02:21:36 DEBUG : Linkbox root 'rclone-test-vaviziy9deta': Purge remote 2026/09/27 02:21:37 DEBUG : Linkbox root 'rclone-test-gipucof7yezu': Purge remote 2026/09/27 02:21:38 DEBUG : Linkbox root 'rclone-test-dezevak7soha': Purge remote 2026/09/27 02:21:40 DEBUG : Linkbox root 'rclone-test-caduxac2siyi': Purge remote 2026/09/27 02:21:41 DEBUG : Linkbox root 'rclone-test-kubuluh8mavi': Purge remote 2026/09/27 02:21:42 DEBUG : Linkbox root 'rclone-test-pecucil8qopa': Purge remote 2026/09/27 02:21:44 DEBUG : Linkbox root 'rclone-test-gekuqul9hebe': Purge remote 2026/09/27 02:21:45 DEBUG : Linkbox root 'rclone-test-roriput7dure': Purge remote --- PASS: TestApplyTransforms (67.36s) === RUN TestCopyInplace run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.55s) === RUN TestLs run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" --- PASS: TestLs (7.57s) === RUN TestLsWithFilesFrom run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:21:59 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/09/27 02:21:59 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (7.99s) === RUN TestLsLong run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" --- PASS: TestLsLong (7.53s) === RUN TestHashSums run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestHashSums (15.23s) === RUN TestCount run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:22:29 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) --- PASS: TestCount (14.83s) === RUN TestDelete run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:22:46 DEBUG : Waiting for deletions to finish 2026/09/27 02:22:47 DEBUG : large: Excluded (Size Filter) 2026/09/27 02:22:47 DEBUG : large: Excluded 2026/09/27 02:22:48 INFO : medium: Deleted 2026/09/27 02:22:48 INFO : small: Deleted --- PASS: TestDelete (9.91s) === RUN TestDeleteFatalError run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:23:31 DEBUG : Waiting for deletions to finish 2026/09/27 02:23:31 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:23:31 ERROR : file7: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (68.08s) === RUN TestMaxDelete run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:24:05 DEBUG : Waiting for deletions to finish 2026/09/27 02:24:05 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/09/27 02:24:07 INFO : large: Deleted 2026/09/27 02:24:07 INFO : medium: Deleted --- PASS: TestMaxDelete (11.02s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:24:15 DEBUG : Waiting for deletions to finish 2026/09/27 02:24:16 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/27 02:24:17 INFO : medium: Deleted 2026/09/27 02:24:17 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (10.27s) === RUN TestMaxDeleteSize run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:24:26 DEBUG : Waiting for deletions to finish 2026/09/27 02:24:27 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/09/27 02:24:28 INFO : large: Deleted 2026/09/27 02:24:28 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (11.06s) === RUN TestReadFile run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" --- PASS: TestReadFile (4.71s) === RUN TestCat run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:24:37 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/09/27 02:24:37 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10) --- PASS: TestCat (11.17s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:24:53 INFO : A2: Making directory 2026/09/27 02:24:54 INFO : A1/B2: Making directory 2026/09/27 02:24:56 INFO : A1/B2/C2: Making directory 2026/09/27 02:24:58 INFO : A1/B1/C3: Making directory 2026/09/27 02:24:59 INFO : A3: Making directory 2026/09/27 02:25:01 INFO : A3/B3: Making directory 2026/09/27 02:25:02 INFO : A3/B3/C4: Making directory 2026/09/27 02:25:08 DEBUG : removing 1 level 3 directories 2026/09/27 02:25:08 INFO : A3/B3/C4: Removing directory 2026/09/27 02:25:14 DEBUG : removing 2 level 3 directories 2026/09/27 02:25:14 INFO : A1/B2/C2: Removing directory 2026/09/27 02:25:14 INFO : A1/B1/C3: Removing directory 2026/09/27 02:25:16 DEBUG : removing 2 level 2 directories 2026/09/27 02:25:16 INFO : A3/B3: Removing directory 2026/09/27 02:25:16 INFO : A1/B2: Removing directory 2026/09/27 02:25:18 DEBUG : removing 2 level 1 directories 2026/09/27 02:25:18 INFO : A3: Removing directory 2026/09/27 02:25:18 INFO : A2: Removing directory 2026/09/27 02:25:24 DEBUG : removing 1 level 3 directories 2026/09/27 02:25:24 INFO : A1/B1/C1: Removing directory 2026/09/27 02:25:24 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/27 02:25:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:24 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/27 02:25:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:25 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/27 02:25:26 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:26 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/27 02:25:27 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:29 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:25:31 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/27 02:25:35 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/27 02:25:35 DEBUG : removing 1 level 2 directories 2026/09/27 02:25:35 INFO : A1/B1: Removing directory 2026/09/27 02:25:35 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/27 02:25:37 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/27 02:25:37 DEBUG : removing 1 level 1 directories 2026/09/27 02:25:37 INFO : A1: Removing directory 2026/09/27 02:25:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/27 02:25:39 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/09/27 02:25:39 DEBUG : removing 1 level 0 directories 2026/09/27 02:25:39 INFO : Linkbox root 'rclone-test-tiqemix3lovi': Removing directory 2026/09/27 02:25:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/09/27 02:25:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/09/27 02:25:41 DEBUG : pacer: Reducing sleep to 200ms 2026/09/27 02:25:41 ERROR : error listing: directory not found 2026/09/27 02:25:42 ERROR : error listing: directory not found --- PASS: TestRmdirsNoLeaveRoot (55.25s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:25:43 INFO : A1: Making directory 2026/09/27 02:25:45 INFO : A1/B1: Making directory 2026/09/27 02:25:46 INFO : A1/B1/C1: Making directory 2026/09/27 02:25:49 DEBUG : removing 1 level 3 directories 2026/09/27 02:25:49 INFO : A1/B1/C1: Removing directory 2026/09/27 02:25:51 DEBUG : removing 1 level 2 directories 2026/09/27 02:25:51 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (13.28s) === RUN TestRmdirsWithFilter run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:25:55 INFO : A1: Making directory 2026/09/27 02:25:56 INFO : A1/B1: Making directory 2026/09/27 02:25:58 INFO : A1/B1/C1: Making directory 2026/09/27 02:26:01 DEBUG : removing 1 level 3 directories 2026/09/27 02:26:01 INFO : A1/B1/C1: Removing directory 2026/09/27 02:26:03 DEBUG : removing 1 level 2 directories 2026/09/27 02:26:03 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (11.83s) === RUN TestCopyURL run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:09 DEBUG : file1: size = 14 OK 2026/09/27 02:26:09 DEBUG : filename.txt: File name found in url 2026/09/27 02:26:12 DEBUG : filename.txt: size = 14 OK 2026/09/27 02:26:12 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/27 02:26:14 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/09/27 02:26:14 DEBUG : headerfilename.txt: size = 14 OK 2026/09/27 02:26:16 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (15.08s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:24 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (3.76s) === RUN TestMoveFile run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:26 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/27 02:26:29 DEBUG : sub/file2: size = 14 OK 2026/09/27 02:26:29 INFO : file1: Copied (new) to: sub/file2 2026/09/27 02:26:29 INFO : file1: Deleted 2026/09/27 02:26:30 DEBUG : sub/file2: size = 14 OK 2026/09/27 02:26:30 DEBUG : file1: Sizes identical 2026/09/27 02:26:30 DEBUG : file1: Unchanged skipping 2026/09/27 02:26:30 INFO : file1: Deleted 2026/09/27 02:26:30 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (8.70s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:34 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/27 02:26:37 DEBUG : file1: size = 14 OK 2026/09/27 02:26:37 INFO : file1: Copied (new) 2026/09/27 02:26:37 INFO : file1: Deleted 2026/09/27 02:26:37 DEBUG : file1: Destination exists, skipping 2026/09/27 02:26:37 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.68s) === RUN TestMoveFileImmutable run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:42 DEBUG : existing: size = 8 (Local file system at /tmp/rclone3409351160) 2026/09/27 02:26:42 DEBUG : existing: size = 6 (Linkbox root 'rclone-test-tiqemix3lovi') 2026/09/27 02:26:42 DEBUG : existing: Sizes differ 2026/09/27 02:26:42 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (4.57s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:26:46 NOTICE: hello: Skipped rename to HELLO as --dry-run is set --- PASS: TestCaseInsensitiveMoveFileDryRun (4.54s) === RUN TestMoveFileBackupDir run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1161: Skipping test as remote does not support server-side move or copy --- SKIP: TestMoveFileBackupDir (0.41s) === RUN TestDirMoveMoveError run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1490: Skipping test on non local remote 2026/09/27 02:27:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:27:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/27 02:27:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:27:31 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/27 02:27:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:27:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/27 02:27:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:27:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/27 02:27:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/27 02:27:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/27 02:27:39 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/27 02:27:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/27 02:27:41 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/27 02:27:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/09/27 02:27:44 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/09/27 02:27:45 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/09/27 02:27:47 DEBUG : pacer: Reducing sleep to 200ms --- SKIP: TestDirMoveMoveError (75.79s) === RUN TestDirMoveContext run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (10.31s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:28:31 INFO : A1/B2: Making directory 2026/09/27 02:28:32 INFO : A1/B1/C3: Making directory 2026/09/27 02:28:51 DEBUG : A2/B1/C2/five: size = 4 OK 2026/09/27 02:28:51 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/09/27 02:28:51 DEBUG : A2/one: size = 3 OK 2026/09/27 02:28:51 INFO : A1/one: Copied (new) to: A2/one 2026/09/27 02:28:52 DEBUG : A2/two: size = 3 OK 2026/09/27 02:28:52 INFO : A1/two: Copied (new) to: A2/two 2026/09/27 02:28:52 DEBUG : A2/B1/three: size = 5 OK 2026/09/27 02:28:52 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/09/27 02:28:52 DEBUG : A2/B1/C1/four: size = 4 OK 2026/09/27 02:28:52 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/09/27 02:28:53 INFO : A1/B1/C2/five: Deleted 2026/09/27 02:28:53 INFO : A1/one: Deleted 2026/09/27 02:28:53 INFO : A1/two: Deleted 2026/09/27 02:28:54 INFO : A1/B1/three: Deleted 2026/09/27 02:28:54 INFO : A1/B1/C1/four: Deleted 2026/09/27 02:29:20 DEBUG : A3/one: size = 3 OK 2026/09/27 02:29:20 INFO : A2/one: Copied (new) to: A3/one 2026/09/27 02:29:21 DEBUG : A3/two: size = 3 OK 2026/09/27 02:29:21 INFO : A2/two: Copied (new) to: A3/two 2026/09/27 02:29:21 DEBUG : A3/B1/three: size = 5 OK 2026/09/27 02:29:21 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/09/27 02:29:21 DEBUG : A3/B1/C1/four: size = 4 OK 2026/09/27 02:29:21 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/09/27 02:29:22 DEBUG : A3/B1/C2/five: size = 4 OK 2026/09/27 02:29:22 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/09/27 02:29:22 INFO : A2/one: Deleted 2026/09/27 02:29:22 INFO : A2/two: Deleted 2026/09/27 02:29:23 INFO : A2/B1/three: Deleted 2026/09/27 02:29:23 INFO : A2/B1/C1/four: Deleted 2026/09/27 02:29:23 INFO : A2/B1/C2/five: Deleted 2026/09/27 02:29:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:23 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/27 02:29:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:24 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/27 02:29:24 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:24 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/27 02:29:25 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:25 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/27 02:29:26 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:28 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:30 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:32 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:29:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/27 02:29:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/27 02:29:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/27 02:29:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/27 02:29:41 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/27 02:29:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/09/27 02:29:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/09/27 02:29:44 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/09/27 02:29:44 DEBUG : pacer: Reducing sleep to 200ms 2026/09/27 02:29:48 INFO : Linkbox root 'rclone-test-tiqemix3lovi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/27 02:30:04 DEBUG : A4/one: size = 3 OK 2026/09/27 02:30:04 INFO : A3/one: Copied (new) to: A4/one 2026/09/27 02:30:05 DEBUG : A4/two: size = 3 OK 2026/09/27 02:30:05 INFO : A3/two: Copied (new) to: A4/two 2026/09/27 02:30:05 DEBUG : A4/B1/C1/four: size = 4 OK 2026/09/27 02:30:05 INFO : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four 2026/09/27 02:30:05 DEBUG : A4/B1/C2/five: size = 4 OK 2026/09/27 02:30:05 INFO : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five 2026/09/27 02:30:06 INFO : A3/one: Deleted 2026/09/27 02:30:06 DEBUG : A4/B1/three: size = 5 OK 2026/09/27 02:30:06 INFO : A3/B1/three: Copied (new) to: A4/B1/three 2026/09/27 02:30:06 INFO : A3/two: Deleted 2026/09/27 02:30:07 INFO : A3/B1/C1/four: Deleted 2026/09/27 02:30:07 INFO : A3/B1/C2/five: Deleted 2026/09/27 02:30:07 INFO : A3/B1/three: Deleted --- PASS: TestDirMove (140.56s) === RUN TestGetFsInfo run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:30:35 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.42s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:30:36 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': File to upload is small (34 bytes), uploading instead of streaming 2026/09/27 02:30:38 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/27 02:30:38 NOTICE: Linkbox root 'rclone-test-tiqemix3lovi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/27 02:30:38 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/27 02:30:38 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/27 02:30:40 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/27 02:30:40 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-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:30:43 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': File to upload is small (34 bytes), uploading instead of streaming 2026/09/27 02:30:45 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/27 02:30:45 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/27 02:30:45 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/27 02:30:47 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/27 02:30:47 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-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:30:51 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': File to upload is small (34 bytes), uploading instead of streaming 2026/09/27 02:30:53 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/27 02:30:53 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical 2026/09/27 02:30:53 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/27 02:30:55 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/27 02:30:55 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:30:58 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': File to upload is small (34 bytes), uploading instead of streaming 2026/09/27 02:31:00 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/27 02:31:00 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/27 02:31:00 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/27 02:31:03 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/27 02:31:03 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (30.49s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.59s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.46s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.42s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (8.01s) === RUN TestRcatMetadata run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1745: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.40s) === RUN TestRcatSize run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:08 DEBUG : potato1: size = 60 OK 2026/09/27 02:31:08 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': File to upload is small (60 bytes), uploading instead of streaming 2026/09/27 02:31:10 DEBUG : potato2: size = 60 OK 2026/09/27 02:31:10 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (7.31s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:15 DEBUG : pacer: low level retry 1/1 (error Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1790483474&Signature=3hJqkeQrt%2Bl7Bwf4fo1O4Uao5lg%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer) 2026/09/27 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/27 02:31:15 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=1790483474&Signature=3hJqkeQrt%2Bl7Bwf4fo1O4Uao5lg%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer 2026/09/27 02:31:15 DEBUG : pacer: Reducing sleep to 300ms 2026/09/27 02:31:15 DEBUG : pacer: Reducing sleep to 225ms --- PASS: TestRcatSizeShortEOF (1.50s) === RUN TestRcatSizeMetadata run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1838: Skipping as destination doesn't support user metadata 2026/09/27 02:31:16 DEBUG : pacer: Reducing sleep to 200ms --- SKIP: TestRcatSizeMetadata (0.51s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:18 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (3.74s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:1975: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:22 DEBUG : potato4: size = 60 (Linkbox root 'rclone-test-tiqemix3lovi') 2026/09/27 02:31:22 DEBUG : potato4: size = 59 (Linkbox root 'rclone-test-tiqemix3lovi') 2026/09/27 02:31:22 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Linkbox root 'rclone-test-tiqemix3lovi') 59 2026/09/27 02:31:22 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:26 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" 2026/09/27 02:31:30 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (11.79s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.41s) --- PASS: TestRcatSizeChecksum/SizeDiffers (3.92s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (3.73s) --- PASS: TestRcatSizeChecksum/NoHashes (3.74s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2020: Skipping test as remote does not support modtime --- SKIP: TestTouchDir (0.40s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2058: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.42s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2079: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.40s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2123: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.41s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2161: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime 2026/09/27 02:31:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:31:33 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/27 02:31:33 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:31:33 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/27 02:31:34 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:31:34 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/27 02:31:35 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/27 02:31:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/27 02:31:36 DEBUG : pacer: Reducing sleep to 1.5s --- SKIP: TestSetDirModTime (3.41s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2212: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata 2026/09/27 02:31:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/27 02:31:40 DEBUG : pacer: Reducing sleep to 843.75ms --- SKIP: TestDirsEqual (3.50s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-tiqemix3lovi'", Local "Local file system at /tmp/rclone3409351160", Modify Window "876000h0m0s" operations_test.go:2281: Skipping as remote can't Move 2026/09/27 02:31:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/27 02:31:42 DEBUG : pacer: Reducing sleep to 474.609375ms --- SKIP: TestRemoveExisting (1.97s) PASS 2026/09/27 02:31:42 DEBUG : Linkbox root 'rclone-test-tiqemix3lovi': Purge remote 2026/09/27 02:31:44 DEBUG : pacer: Reducing sleep to 355.957031ms "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSizeOnly|TestCheckSumDownload|TestCopyDirMetadata|TestCopyInplace|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDeleteFatalError|TestDirMove|TestDirMoveContext|TestDirMoveMoveError|TestDirsEqual|TestGetFsInfo|TestHashSums|TestLs|TestLsLong|TestLsWithFilesFrom|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileImmutable|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestRcatSizeChecksum$/^(Corrupted|IgnoreChecksum|NoHashes|SizeDiffers)$'" - Finished OK in 12m14.224459333s (try 2/5)