"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestApplyTransforms|TestMaxDeleteSize|TestTouchDir)$'" - Starting (try 2/5)
2025/10/26 04:04:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-gowupev0viku"
2025/10/26 04:04:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:36 DEBUG : Creating backend with remote "/tmp/rclone163348570"
=== RUN   TestApplyTransforms
2025/10/26 04:04:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-hevobot5mowo"
2025/10/26 04:04:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:37 DEBUG : Creating backend with remote "/tmp/rclone1329850740"
    run.go:180: Remote "Dropbox root 'rclone-test-hevobot5mowo'", Local "Local file system at /tmp/rclone1329850740", Modify Window "1s"
2025/10/26 04:04:38 DEBUG : hello, world!: Uploading chunk 1/1
2025/10/26 04:04:38 DEBUG : hello, world!: Uploading chunk 2/1
2025/10/26 04:04:38 DEBUG : Dropbox root 'rclone-test-hevobot5mowo': Adding "hello, world!" to batch
2025/10/26 04:04:39 DEBUG : Dropbox root 'rclone-test-hevobot5mowo': Batch idle for 500ms so committing
2025/10/26 04:04:39 DEBUG : Dropbox root 'rclone-test-hevobot5mowo': Committing sync batch length 1 starting with: hello, world!
2025/10/26 04:04:40 DEBUG : Dropbox root 'rclone-test-hevobot5mowo': Committed sync batch length 1 starting with: hello, world!
upper checkfile vs. lower remote (without normalization)
2025/10/26 04:04:40 ERROR : hello, world!: sum not found
2025/10/26 04:04:40 ERROR : HELLO, WORLD!: file not in Dropbox root 'rclone-test-hevobot5mowo'
2025/10/26 04:04:40 NOTICE: Dropbox root 'rclone-test-hevobot5mowo': 1 files missing
2025/10/26 04:04:40 NOTICE: 1 hashes missing
2025/10/26 04:04:40 NOTICE: Dropbox root 'rclone-test-hevobot5mowo': 1 differences found
2025/10/26 04:04:40 NOTICE: Dropbox root 'rclone-test-hevobot5mowo': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2025/10/26 04:04:41 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/10/26 04:04:41 NOTICE: Dropbox root 'rclone-test-hevobot5mowo': 0 differences found
2025/10/26 04:04:41 NOTICE: Dropbox root 'rclone-test-hevobot5mowo': 1 matching files
2025/10/26 04:04:41 DEBUG : Creating backend with remote "TestDropbox:rclone-test-vapihih6faru"
2025/10/26 04:04:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:41 DEBUG : Creating backend with remote "/tmp/rclone3957582779"
    run.go:180: Remote "Dropbox root 'rclone-test-vapihih6faru'", Local "Local file system at /tmp/rclone3957582779", Modify Window "1s"
2025/10/26 04:04:42 DEBUG : HELLO, WORLD!: Uploading chunk 1/1
2025/10/26 04:04:42 DEBUG : HELLO, WORLD!: Uploading chunk 2/1
2025/10/26 04:04:43 DEBUG : Dropbox root 'rclone-test-vapihih6faru': Adding "HELLO, WORLD!" to batch
2025/10/26 04:04:43 DEBUG : Dropbox root 'rclone-test-vapihih6faru': Batch idle for 500ms so committing
2025/10/26 04:04:43 DEBUG : Dropbox root 'rclone-test-vapihih6faru': Committing sync batch length 1 starting with: HELLO, WORLD!
2025/10/26 04:04:45 DEBUG : Dropbox root 'rclone-test-vapihih6faru': Committed sync batch length 1 starting with: HELLO, WORLD!
lower checkfile vs. upper remote (without normalization)
2025/10/26 04:04:45 ERROR : HELLO, WORLD!: sum not found
2025/10/26 04:04:45 ERROR : hello, world!: file not in Dropbox root 'rclone-test-vapihih6faru'
2025/10/26 04:04:45 NOTICE: Dropbox root 'rclone-test-vapihih6faru': 1 files missing
2025/10/26 04:04:45 NOTICE: 1 hashes missing
2025/10/26 04:04:45 NOTICE: Dropbox root 'rclone-test-vapihih6faru': 1 differences found
2025/10/26 04:04:45 NOTICE: Dropbox root 'rclone-test-vapihih6faru': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2025/10/26 04:04:46 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/10/26 04:04:46 NOTICE: Dropbox root 'rclone-test-vapihih6faru': 0 differences found
2025/10/26 04:04:46 NOTICE: Dropbox root 'rclone-test-vapihih6faru': 1 matching files
2025/10/26 04:04:46 DEBUG : Creating backend with remote "TestDropbox:rclone-test-bidusey0zike"
2025/10/26 04:04:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:46 DEBUG : Creating backend with remote "/tmp/rclone2590651908"
    run.go:180: Remote "Dropbox root 'rclone-test-bidusey0zike'", Local "Local file system at /tmp/rclone2590651908", Modify Window "1s"
2025/10/26 04:04:47 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/26 04:04:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/26 04:04:47 DEBUG : pacer: Reducing sleep to 15ms
2025/10/26 04:04:47 DEBUG : pacer: Reducing sleep to 11.25ms
2025/10/26 04:04:48 DEBUG : HeLlO, wOrLd!: Uploading chunk 1/1
2025/10/26 04:04:48 DEBUG : pacer: Reducing sleep to 10ms
2025/10/26 04:04:48 DEBUG : HeLlO, wOrLd!: Uploading chunk 2/1
2025/10/26 04:04:48 DEBUG : Dropbox root 'rclone-test-bidusey0zike': Adding "HeLlO, wOrLd!" to batch
2025/10/26 04:04:49 DEBUG : Dropbox root 'rclone-test-bidusey0zike': Batch idle for 500ms so committing
2025/10/26 04:04:49 DEBUG : Dropbox root 'rclone-test-bidusey0zike': Committing sync batch length 1 starting with: HeLlO, wOrLd!
2025/10/26 04:04:50 DEBUG : Dropbox root 'rclone-test-bidusey0zike': Committed sync batch length 1 starting with: HeLlO, wOrLd!
lower checkfile vs. upperlowermixed remote (without normalization)
2025/10/26 04:04:50 ERROR : HeLlO, wOrLd!: sum not found
2025/10/26 04:04:50 ERROR : hello, world!: file not in Dropbox root 'rclone-test-bidusey0zike'
2025/10/26 04:04:50 NOTICE: Dropbox root 'rclone-test-bidusey0zike': 1 files missing
2025/10/26 04:04:50 NOTICE: 1 hashes missing
2025/10/26 04:04:50 NOTICE: Dropbox root 'rclone-test-bidusey0zike': 1 differences found
2025/10/26 04:04:50 NOTICE: Dropbox root 'rclone-test-bidusey0zike': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2025/10/26 04:04:51 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/10/26 04:04:51 NOTICE: Dropbox root 'rclone-test-bidusey0zike': 0 differences found
2025/10/26 04:04:51 NOTICE: Dropbox root 'rclone-test-bidusey0zike': 1 matching files
2025/10/26 04:04:51 DEBUG : Creating backend with remote "TestDropbox:rclone-test-jagafiz7faya"
2025/10/26 04:04:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:51 DEBUG : Creating backend with remote "/tmp/rclone3483240228"
    run.go:180: Remote "Dropbox root 'rclone-test-jagafiz7faya'", Local "Local file system at /tmp/rclone3483240228", Modify Window "1s"
2025/10/26 04:04:52 DEBUG : HELLO, WORLD!: Uploading chunk 1/1
2025/10/26 04:04:52 DEBUG : HELLO, WORLD!: Uploading chunk 2/1
2025/10/26 04:04:53 DEBUG : Dropbox root 'rclone-test-jagafiz7faya': Adding "HELLO, WORLD!" to batch
2025/10/26 04:04:53 DEBUG : Dropbox root 'rclone-test-jagafiz7faya': Batch idle for 500ms so committing
2025/10/26 04:04:53 DEBUG : Dropbox root 'rclone-test-jagafiz7faya': Committing sync batch length 1 starting with: HELLO, WORLD!
2025/10/26 04:04:54 DEBUG : Dropbox root 'rclone-test-jagafiz7faya': Committed sync batch length 1 starting with: HELLO, WORLD!
upperlowermixed checkfile vs. upper remote (without normalization)
2025/10/26 04:04:55 ERROR : HELLO, WORLD!: sum not found
2025/10/26 04:04:55 ERROR : HeLlO, wOrLd!: file not in Dropbox root 'rclone-test-jagafiz7faya'
2025/10/26 04:04:55 NOTICE: Dropbox root 'rclone-test-jagafiz7faya': 1 files missing
2025/10/26 04:04:55 NOTICE: 1 hashes missing
2025/10/26 04:04:55 NOTICE: Dropbox root 'rclone-test-jagafiz7faya': 1 differences found
2025/10/26 04:04:55 NOTICE: Dropbox root 'rclone-test-jagafiz7faya': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2025/10/26 04:04:55 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/10/26 04:04:55 NOTICE: Dropbox root 'rclone-test-jagafiz7faya': 0 differences found
2025/10/26 04:04:55 NOTICE: Dropbox root 'rclone-test-jagafiz7faya': 1 matching files
2025/10/26 04:04:55 DEBUG : Creating backend with remote "TestDropbox:rclone-test-bucerib2pedu"
2025/10/26 04:04:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:04:56 DEBUG : Creating backend with remote "/tmp/rclone358577623"
    run.go:180: Remote "Dropbox root 'rclone-test-bucerib2pedu'", Local "Local file system at /tmp/rclone358577623", Modify Window "1s"
2025/10/26 04:04:57 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2025/10/26 04:04:57 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2025/10/26 04:04:58 DEBUG : Dropbox root 'rclone-test-bucerib2pedu': Adding "測試_Русский___ě_áñ" to batch
2025/10/26 04:04:58 DEBUG : Dropbox root 'rclone-test-bucerib2pedu': Batch idle for 500ms so committing
2025/10/26 04:04:58 DEBUG : Dropbox root 'rclone-test-bucerib2pedu': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/10/26 04:04:59 DEBUG : Dropbox root 'rclone-test-bucerib2pedu': Committed sync batch length 1 starting with: 測試_Русский___ě_áñ
NFD checkfile vs. NFC remote (without normalization)
2025/10/26 04:04:59 ERROR : 測試_Русский___ě_áñ: sum not found
2025/10/26 04:04:59 ERROR : 測試_Русский___ě_áñ: file not in Dropbox root 'rclone-test-bucerib2pedu'
2025/10/26 04:04:59 NOTICE: Dropbox root 'rclone-test-bucerib2pedu': 1 files missing
2025/10/26 04:04:59 NOTICE: 1 hashes missing
2025/10/26 04:04:59 NOTICE: Dropbox root 'rclone-test-bucerib2pedu': 1 differences found
2025/10/26 04:04:59 NOTICE: Dropbox root 'rclone-test-bucerib2pedu': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2025/10/26 04:05:00 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/10/26 04:05:00 NOTICE: Dropbox root 'rclone-test-bucerib2pedu': 0 differences found
2025/10/26 04:05:00 NOTICE: Dropbox root 'rclone-test-bucerib2pedu': 1 matching files
2025/10/26 04:05:00 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yekikiy0pawi"
2025/10/26 04:05:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/26 04:05:00 DEBUG : Creating backend with remote "/tmp/rclone1167556130"
    run.go:180: Remote "Dropbox root 'rclone-test-yekikiy0pawi'", Local "Local file system at /tmp/rclone1167556130", Modify Window "1s"
2025/10/26 04:05:02 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2025/10/26 04:05:02 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2025/10/26 04:05:02 DEBUG : Dropbox root 'rclone-test-yekikiy0pawi': Adding "測試_Русский___ě_áñ" to batch
2025/10/26 04:05:03 DEBUG : Dropbox root 'rclone-test-yekikiy0pawi': Batch idle for 500ms so committing
2025/10/26 04:05:03 DEBUG : Dropbox root 'rclone-test-yekikiy0pawi': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/10/26 04:05:05 DEBUG : Dropbox root 'rclone-test-yekikiy0pawi': Committed sync batch length 1 starting with: 測試_Русский___ě_áñ
    check_test.go:570: Fs is incapable of running test, skipping: NFC checkfile vs. NFD remote (expected: 測試_Русский___ě_áñ (NFD) actual: 測試_Русский___ě_áñ (NFC))
2025/10/26 04:05:05 DEBUG : Dropbox root 'rclone-test-yekikiy0pawi': Purge remote
2025/10/26 04:05:06 DEBUG : Dropbox root 'rclone-test-bucerib2pedu': Purge remote
2025/10/26 04:05:06 DEBUG : Dropbox root 'rclone-test-jagafiz7faya': Purge remote
2025/10/26 04:05:07 DEBUG : Dropbox root 'rclone-test-bidusey0zike': Purge remote
2025/10/26 04:05:08 DEBUG : Dropbox root 'rclone-test-vapihih6faru': Purge remote
2025/10/26 04:05:08 DEBUG : Dropbox root 'rclone-test-hevobot5mowo': Purge remote
--- SKIP: TestApplyTransforms (32.32s)
=== RUN   TestMaxDeleteSize
    run.go:180: Remote "Dropbox root 'rclone-test-gowupev0viku'", Local "Local file system at /tmp/rclone163348570", Modify Window "1s"
2025/10/26 04:05:10 DEBUG : small: Uploading chunk 1/1
2025/10/26 04:05:10 DEBUG : small: Uploading chunk 2/1
2025/10/26 04:05:11 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "small" to batch
2025/10/26 04:05:11 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:11 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: small
2025/10/26 04:05:12 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: small
2025/10/26 04:05:12 DEBUG : medium: Uploading chunk 1/1
2025/10/26 04:05:12 DEBUG : medium: Uploading chunk 2/1
2025/10/26 04:05:13 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "medium" to batch
2025/10/26 04:05:14 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:14 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: medium
2025/10/26 04:05:14 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: medium
2025/10/26 04:05:15 DEBUG : large: Uploading chunk 1/1
2025/10/26 04:05:15 DEBUG : large: Uploading chunk 2/1
2025/10/26 04:05:15 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "large" to batch
2025/10/26 04:05:16 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:16 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: large
2025/10/26 04:05:16 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: large
2025/10/26 04:05:17 DEBUG : Waiting for deletions to finish
2025/10/26 04:05:17 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2025/10/26 04:05:17 INFO  : large: Deleted
2025/10/26 04:05:18 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (10.44s)
=== RUN   TestTouchDir
    run.go:180: Remote "Dropbox root 'rclone-test-gowupev0viku'", Local "Local file system at /tmp/rclone163348570", Modify Window "1s"
2025/10/26 04:05:19 DEBUG : potato2: Uploading chunk 1/1
2025/10/26 04:05:20 DEBUG : potato2: Uploading chunk 2/1
2025/10/26 04:05:20 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "potato2" to batch
2025/10/26 04:05:21 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:21 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: potato2
2025/10/26 04:05:22 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: potato2
2025/10/26 04:05:23 DEBUG : empty space: Uploading chunk 1/1
2025/10/26 04:05:23 DEBUG : empty space: Uploading chunk 2/1
2025/10/26 04:05:23 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "empty space" to batch
2025/10/26 04:05:24 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:24 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: empty space
2025/10/26 04:05:24 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: empty space
2025/10/26 04:05:25 DEBUG : sub dir/potato3: Uploading chunk 1/1
2025/10/26 04:05:25 DEBUG : sub dir/potato3: Uploading chunk 2/1
2025/10/26 04:05:25 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Adding "sub dir/potato3" to batch
2025/10/26 04:05:26 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Batch idle for 500ms so committing
2025/10/26 04:05:26 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committing sync batch length 1 starting with: sub dir/potato3
2025/10/26 04:05:27 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Committed sync batch length 1 starting with: sub dir/potato3
2025/10/26 04:05:27 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Touching "empty space"
2025/10/26 04:05:27 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Touching "potato2"
2025/10/26 04:05:27 ERROR : empty space: failed to touch: can't set modified time without deleting existing object
2025/10/26 04:05:27 ERROR : potato2: failed to touch: can't set modified time without deleting existing object
2025/10/26 04:05:27 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Touching "sub dir/potato3"
2025/10/26 04:05:27 ERROR : sub dir/potato3: failed to touch: can't set modified time without deleting existing object
--- PASS: TestTouchDir (11.89s)
PASS
2025/10/26 04:05:31 DEBUG : Dropbox root 'rclone-test-gowupev0viku': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestApplyTransforms|TestMaxDeleteSize|TestTouchDir)$'" - Finished OK in 55.64209848s (try 2/5)