"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestCopyFileMaxTransfer|TestDirMove|TestRcatSize|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Starting (try 2/5)
2026/09/23 05:52:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rasezak6ximo"
2026/09/23 05:52:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:52:22 DEBUG : Linkbox root 'rclone-test-rasezak6ximo': Using cached web token
2026/09/23 05:52:23 DEBUG : Creating backend with remote "/tmp/rclone1792261373"
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 05:52:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rasezak6ximo/data"
2026/09/23 05:52:23 DEBUG : Linkbox root 'rclone-test-rasezak6ximo/data': Using cached web token
2026/09/23 05:52:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:52:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 05:52:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 05:52:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:29 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:52:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:299: Retry Put of "data/banana" to Linkbox root 'rclone-test-rasezak6ximo': 1/10 (Update second step failed: invalid character '<' looking for beginning of value)
2026/09/23 05:52:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:51 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:52:53 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:52:55 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 05:52:58 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 05:52:58 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 05:52:59 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 05:53:00 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 05:53:01 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 05:53:01 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 05:53:02 DEBUG : pacer: Reducing sleep to 200ms
=== RUN   TestCheckSumDownload/subtest1
2026/09/23 05:53:05 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest2
2026/09/23 05:53:10 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest3
2026/09/23 05:53:14 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest4
2026/09/23 05:53:19 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest5
2026/09/23 05:53:23 DEBUG : test.sum: Update: removing old file
2026/09/23 05:53:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:53:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 05:53:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 05:53:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:28 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:53:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:37 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:39 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
=== NAME  TestCheckSumDownload
    run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-rasezak6ximo': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value)
2026/09/23 05:53:41 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:47 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:49 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:51 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:53:53 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:53:53 DEBUG : test.sum: Update: removing old file
2026/09/23 05:53:57 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 05:53:57 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 05:53:59 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 05:53:59 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 05:54:00 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 05:54:01 DEBUG : pacer: Reducing sleep to 266.967773ms
=== RUN   TestCheckSumDownload/subtest6
2026/09/23 05:54:02 DEBUG : data/banana: Update: removing old file
2026/09/23 05:54:05 DEBUG : data/potato: Update: removing old file
2026/09/23 05:54:09 DEBUG : test.sum: Update: removing old file
2026/09/23 05:54:12 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/09/23 05:54:12 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/09/23 05:54:13 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
2026/09/23 05:54:13 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10)
2026/09/23 05:54:14 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10)
2026/09/23 05:54:16 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10)
2026/09/23 05:54:19 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10)
2026/09/23 05:54:26 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10)
2026/09/23 05:54:39 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10)
2026/09/23 05:55:05 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10)
=== NAME  TestCheckSumDownload
    run.go:303: Failed to put "test.sum" to "Linkbox root 'rclone-test-rasezak6ximo'": object not found
--- FAIL: TestCheckSumDownload (219.46s)
    --- PASS: TestCheckSumDownload/subtest1 (1.29s)
    --- PASS: TestCheckSumDownload/subtest2 (0.47s)
    --- PASS: TestCheckSumDownload/subtest3 (0.69s)
    --- PASS: TestCheckSumDownload/subtest4 (0.68s)
    --- PASS: TestCheckSumDownload/subtest5 (0.68s)
    --- PASS: TestCheckSumDownload/subtest6 (0.98s)
=== RUN   TestApplyTransforms
2026/09/23 05:56:03 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dokigey8godi"
2026/09/23 05:56:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:03 DEBUG : Linkbox root 'rclone-test-dokigey8godi': Using cached web token
2026/09/23 05:56:04 DEBUG : Creating backend with remote "/tmp/rclone2391986329"
    run.go:198: Remote "Linkbox root 'rclone-test-dokigey8godi'", Local "Local file system at /tmp/rclone2391986329", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/09/23 05:56:08 ERROR : hello, world!: sum not found
2026/09/23 05:56:08 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-dokigey8godi'
2026/09/23 05:56:08 NOTICE: Linkbox root 'rclone-test-dokigey8godi': 1 files missing
2026/09/23 05:56:08 NOTICE: 1 hashes missing
2026/09/23 05:56:08 NOTICE: Linkbox root 'rclone-test-dokigey8godi': 1 differences found
2026/09/23 05:56:08 NOTICE: Linkbox root 'rclone-test-dokigey8godi': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/09/23 05:56:08 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/23 05:56:08 NOTICE: Linkbox root 'rclone-test-dokigey8godi': 0 differences found
2026/09/23 05:56:08 NOTICE: Linkbox root 'rclone-test-dokigey8godi': 1 matching files
2026/09/23 05:56:08 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yinutey3siji"
2026/09/23 05:56:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:08 DEBUG : Linkbox root 'rclone-test-yinutey3siji': Using cached web token
2026/09/23 05:56:09 DEBUG : Creating backend with remote "/tmp/rclone1836179377"
    run.go:198: Remote "Linkbox root 'rclone-test-yinutey3siji'", Local "Local file system at /tmp/rclone1836179377", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/09/23 05:56:13 ERROR : HELLO, WORLD!: sum not found
2026/09/23 05:56:13 ERROR : hello, world!: file not in Linkbox root 'rclone-test-yinutey3siji'
2026/09/23 05:56:13 NOTICE: Linkbox root 'rclone-test-yinutey3siji': 1 files missing
2026/09/23 05:56:13 NOTICE: 1 hashes missing
2026/09/23 05:56:13 NOTICE: Linkbox root 'rclone-test-yinutey3siji': 1 differences found
2026/09/23 05:56:13 NOTICE: Linkbox root 'rclone-test-yinutey3siji': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/09/23 05:56:14 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/23 05:56:14 NOTICE: Linkbox root 'rclone-test-yinutey3siji': 0 differences found
2026/09/23 05:56:14 NOTICE: Linkbox root 'rclone-test-yinutey3siji': 1 matching files
2026/09/23 05:56:14 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-guvoxen6jiyu"
2026/09/23 05:56:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:14 DEBUG : Linkbox root 'rclone-test-guvoxen6jiyu': Using cached web token
2026/09/23 05:56:15 DEBUG : Creating backend with remote "/tmp/rclone1142140373"
    run.go:198: Remote "Linkbox root 'rclone-test-guvoxen6jiyu'", Local "Local file system at /tmp/rclone1142140373", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/09/23 05:56:19 ERROR : HeLlO, wOrLd!: sum not found
2026/09/23 05:56:19 ERROR : hello, world!: file not in Linkbox root 'rclone-test-guvoxen6jiyu'
2026/09/23 05:56:19 NOTICE: Linkbox root 'rclone-test-guvoxen6jiyu': 1 files missing
2026/09/23 05:56:19 NOTICE: 1 hashes missing
2026/09/23 05:56:19 NOTICE: Linkbox root 'rclone-test-guvoxen6jiyu': 1 differences found
2026/09/23 05:56:19 NOTICE: Linkbox root 'rclone-test-guvoxen6jiyu': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/09/23 05:56:19 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/23 05:56:19 NOTICE: Linkbox root 'rclone-test-guvoxen6jiyu': 0 differences found
2026/09/23 05:56:19 NOTICE: Linkbox root 'rclone-test-guvoxen6jiyu': 1 matching files
2026/09/23 05:56:19 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kazigiw2piwi"
2026/09/23 05:56:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:19 DEBUG : Linkbox root 'rclone-test-kazigiw2piwi': Using cached web token
2026/09/23 05:56:20 DEBUG : Creating backend with remote "/tmp/rclone854098792"
    run.go:198: Remote "Linkbox root 'rclone-test-kazigiw2piwi'", Local "Local file system at /tmp/rclone854098792", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/09/23 05:56:24 ERROR : HELLO, WORLD!: sum not found
2026/09/23 05:56:24 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-kazigiw2piwi'
2026/09/23 05:56:24 NOTICE: Linkbox root 'rclone-test-kazigiw2piwi': 1 files missing
2026/09/23 05:56:24 NOTICE: 1 hashes missing
2026/09/23 05:56:24 NOTICE: Linkbox root 'rclone-test-kazigiw2piwi': 1 differences found
2026/09/23 05:56:24 NOTICE: Linkbox root 'rclone-test-kazigiw2piwi': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/09/23 05:56:24 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/23 05:56:24 NOTICE: Linkbox root 'rclone-test-kazigiw2piwi': 0 differences found
2026/09/23 05:56:24 NOTICE: Linkbox root 'rclone-test-kazigiw2piwi': 1 matching files
2026/09/23 05:56:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-nabagut5cowa"
2026/09/23 05:56:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:24 DEBUG : Linkbox root 'rclone-test-nabagut5cowa': Using cached web token
2026/09/23 05:56:25 DEBUG : Creating backend with remote "/tmp/rclone4280823520"
    run.go:198: Remote "Linkbox root 'rclone-test-nabagut5cowa'", Local "Local file system at /tmp/rclone4280823520", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/09/23 05:56:30 ERROR : 測試_Русский___ě_áñ: sum not found
2026/09/23 05:56:30 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-nabagut5cowa'
2026/09/23 05:56:30 NOTICE: Linkbox root 'rclone-test-nabagut5cowa': 1 files missing
2026/09/23 05:56:30 NOTICE: 1 hashes missing
2026/09/23 05:56:30 NOTICE: Linkbox root 'rclone-test-nabagut5cowa': 1 differences found
2026/09/23 05:56:30 NOTICE: Linkbox root 'rclone-test-nabagut5cowa': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/09/23 05:56:31 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/23 05:56:31 NOTICE: Linkbox root 'rclone-test-nabagut5cowa': 0 differences found
2026/09/23 05:56:31 NOTICE: Linkbox root 'rclone-test-nabagut5cowa': 1 matching files
2026/09/23 05:56:31 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-piduyam6rohe"
2026/09/23 05:56:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 05:56:31 DEBUG : Linkbox root 'rclone-test-piduyam6rohe': Using cached web token
2026/09/23 05:56:31 DEBUG : Creating backend with remote "/tmp/rclone3117981644"
    run.go:198: Remote "Linkbox root 'rclone-test-piduyam6rohe'", Local "Local file system at /tmp/rclone3117981644", Modify Window "876000h0m0s"
2026/09/23 05:56:32 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:32 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:56:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:32 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 05:56:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 05:56:33 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:33 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:56:35 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:37 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:39 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:41 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:43 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:45 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:255: Failed to mkdir "Linkbox root 'rclone-test-piduyam6rohe'": failed to make directory: CreateDir failed: invalid character '<' looking for beginning of value
2026/09/23 05:56:45 DEBUG : Linkbox root 'rclone-test-piduyam6rohe': Purge remote
2026/09/23 05:56:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:49 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:56:55 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:56:55 NOTICE: purge failed: directory not found
2026/09/23 05:56:55 DEBUG : Linkbox root 'rclone-test-nabagut5cowa': Purge remote
2026/09/23 05:56:57 DEBUG : Linkbox root 'rclone-test-kazigiw2piwi': Purge remote
2026/09/23 05:56:58 DEBUG : Linkbox root 'rclone-test-guvoxen6jiyu': Purge remote
2026/09/23 05:56:59 DEBUG : Linkbox root 'rclone-test-yinutey3siji': Purge remote
2026/09/23 05:57:01 DEBUG : Linkbox root 'rclone-test-dokigey8godi': Purge remote
--- FAIL: TestApplyTransforms (59.14s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 05:57:02 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/09/23 05:57:06 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/09/23 05:57:06 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/09/23 05:57:07 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/09/23 05:57:07 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Update err in reading file: max transfer limit reached as set by --max-transfer
2026/09/23 05:57:07 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/09/23 05:57:08 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/09/23 05:57:11 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/09/23 05:57:11 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (14.19s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 05:57:16 INFO  : A1: Making directory
2026/09/23 05:57:18 INFO  : A1/B1: Making directory
2026/09/23 05:57:19 INFO  : A1/B1/C1: Making directory
2026/09/23 05:57:22 DEBUG : removing 1 level 3 directories
2026/09/23 05:57:22 INFO  : A1/B1/C1: Removing directory
2026/09/23 05:57:24 DEBUG : removing 1 level 2 directories
2026/09/23 05:57:24 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (11.93s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 05:57:32 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:32 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:57:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:32 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 05:57:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 05:57:33 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:33 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:57:35 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:37 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:39 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:41 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:43 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:45 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:49 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:57:55 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:57:58 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 05:57:58 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 05:57:59 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 05:58:01 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 05:58:02 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 05:58:02 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 05:58:04 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 05:58:04 DEBUG : pacer: Reducing sleep to 200ms
2026/09/23 05:58:11 INFO  : A1/B2: Making directory
2026/09/23 05:58:13 INFO  : A1/B1/C3: Making directory
2026/09/23 05:58:32 DEBUG : A2/B1/C1/four: size = 4 OK
2026/09/23 05:58:32 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/09/23 05:58:32 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:32 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:58:32 DEBUG : pacer: Reducing sleep to 300ms
2026/09/23 05:58:32 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:32 DEBUG : pacer: Rate limited, increasing sleep to 600ms
2026/09/23 05:58:32 DEBUG : pacer: Reducing sleep to 450ms
2026/09/23 05:58:32 DEBUG : pacer: Reducing sleep to 337.5ms
2026/09/23 05:58:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:32 DEBUG : pacer: Rate limited, increasing sleep to 675ms
2026/09/23 05:58:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:33 DEBUG : pacer: Rate limited, increasing sleep to 1.35s
2026/09/23 05:58:33 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:33 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:58:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:37 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:39 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:41 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:43 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:47 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:58:55 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:58:55 DEBUG : A2/two: size = 3 OK
2026/09/23 05:58:55 INFO  : A1/two: Copied (new) to: A2/two
2026/09/23 05:58:57 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 05:58:57 DEBUG : A2/B1/three: size = 5 OK
2026/09/23 05:58:57 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/09/23 05:59:00 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 05:59:00 DEBUG : A2/one: size = 3 OK
2026/09/23 05:59:00 INFO  : A1/one: Copied (new) to: A2/one
2026/09/23 05:59:00 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 05:59:00 INFO  : A1/B1/C1/four: Deleted
2026/09/23 05:59:01 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 05:59:01 DEBUG : A2/B1/C2/five: size = 4 OK
2026/09/23 05:59:01 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/09/23 05:59:03 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 05:59:03 INFO  : A1/two: Deleted
2026/09/23 05:59:03 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 05:59:03 INFO  : A1/B1/three: Deleted
2026/09/23 05:59:03 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 05:59:03 INFO  : A1/one: Deleted
2026/09/23 05:59:04 DEBUG : pacer: Reducing sleep to 200ms
2026/09/23 05:59:04 INFO  : A1/B1/C2/five: Deleted
2026/09/23 05:59:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 05:59:29 DEBUG : pacer: Reducing sleep to 300ms
2026/09/23 05:59:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:29 DEBUG : pacer: Rate limited, increasing sleep to 600ms
2026/09/23 05:59:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:29 DEBUG : pacer: Rate limited, increasing sleep to 1.2s
2026/09/23 05:59:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:30 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 05:59:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:35 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:37 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:41 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:49 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:51 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:53 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 05:59:55 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 05:59:57 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 06:00:00 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 06:00:00 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 06:00:02 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 06:00:03 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 06:00:03 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 06:00:03 DEBUG : A3/one: size = 3 OK
2026/09/23 06:00:03 INFO  : A2/one: Copied (new) to: A3/one
2026/09/23 06:00:03 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 06:00:04 DEBUG : pacer: Reducing sleep to 200ms
2026/09/23 06:00:04 DEBUG : A3/two: size = 3 OK
2026/09/23 06:00:04 INFO  : A2/two: Copied (new) to: A3/two
2026/09/23 06:00:04 DEBUG : A3/B1/C1/four: size = 4 OK
2026/09/23 06:00:04 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/09/23 06:00:04 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/09/23 06:00:05 DEBUG : A3/B1/three: size = 5 OK
2026/09/23 06:00:05 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/09/23 06:00:05 DEBUG : A3/B1/C2/five: size = 4 OK
2026/09/23 06:00:05 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/09/23 06:00:05 INFO  : A2/one: Deleted
2026/09/23 06:00:05 INFO  : A2/two: Deleted
2026/09/23 06:00:06 INFO  : A2/B1/C1/four: Deleted
2026/09/23 06:00:06 INFO  : A2/B1/three: Deleted
2026/09/23 06:00:06 INFO  : A2/B1/C2/five: Deleted
2026/09/23 06:00:17 INFO  : Linkbox root 'rclone-test-rasezak6ximo': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/09/23 06:00:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 06:00:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 06:00:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 06:00:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:29 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 06:00:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:33 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:35 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:37 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:41 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:43 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:49 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:55 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:00:57 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 06:00:59 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 06:01:00 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 06:01:01 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 06:01:02 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 06:01:03 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 06:01:03 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 06:01:04 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 06:01:04 DEBUG : pacer: Reducing sleep to 200ms
2026/09/23 06:01:08 DEBUG : A4/B1/C2/five: size = 4 OK
2026/09/23 06:01:08 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/09/23 06:01:08 DEBUG : A4/one: size = 3 OK
2026/09/23 06:01:08 INFO  : A3/one: Copied (new) to: A4/one
2026/09/23 06:01:08 DEBUG : A4/two: size = 3 OK
2026/09/23 06:01:08 INFO  : A3/two: Copied (new) to: A4/two
2026/09/23 06:01:09 DEBUG : A4/B1/three: size = 5 OK
2026/09/23 06:01:09 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/09/23 06:01:09 DEBUG : A4/B1/C1/four: size = 4 OK
2026/09/23 06:01:09 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/09/23 06:01:09 INFO  : A3/B1/C2/five: Deleted
2026/09/23 06:01:10 INFO  : A3/one: Deleted
2026/09/23 06:01:10 INFO  : A3/two: Deleted
2026/09/23 06:01:11 INFO  : A3/B1/three: Deleted
2026/09/23 06:01:11 INFO  : A3/B1/C1/four: Deleted
2026/09/23 06:01:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/23 06:01:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:27 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/23 06:01:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/23 06:01:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:28 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/23 06:01:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:34 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:36 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:38 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:40 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:42 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:44 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:46 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:48 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:50 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:52 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:54 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/23 06:01:56 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/23 06:01:58 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/23 06:02:01 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/23 06:02:02 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/23 06:02:02 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/23 06:02:04 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/23 06:02:04 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/23 06:02:05 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/23 06:02:06 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestDirMove (283.85s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 06:02:12 DEBUG : Linkbox root 'rclone-test-rasezak6ximo': File to upload is small (34 bytes), uploading instead of streaming
2026/09/23 06:02:14 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/09/23 06:02:14 NOTICE: Linkbox root 'rclone-test-rasezak6ximo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/09/23 06:02:14 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/23 06:02:14 DEBUG : Linkbox root 'rclone-test-rasezak6ximo': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/09/23 06:02:16 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/09/23 06:02:16 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (7.62s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.62s)
=== RUN   TestRcatSize
    run.go:198: Remote "Linkbox root 'rclone-test-rasezak6ximo'", Local "Local file system at /tmp/rclone1792261373", Modify Window "876000h0m0s"
2026/09/23 06:02:22 DEBUG : potato1: size = 60 OK
2026/09/23 06:02:22 DEBUG : Linkbox root 'rclone-test-rasezak6ximo': File to upload is small (60 bytes), uploading instead of streaming
2026/09/23 06:02:24 DEBUG : potato2: size = 60 OK
2026/09/23 06:02:24 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (7.57s)
FAIL
2026/09/23 06:02:27 DEBUG : Linkbox root 'rclone-test-rasezak6ximo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestCopyFileMaxTransfer|TestDirMove|TestRcatSize|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Finished ERROR in 10m6.13531005s (try 2/5): exit status 1: Failed [TestCheckSumDownload TestApplyTransforms]