"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestApplyTransforms$|^TestRcat$/^withChecksum=true,ignoreChecksum=false$'" - Starting (try 2/5)
2025/07/31 02:35:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-quzefit1codo"
2025/07/31 02:35:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:35:49 DEBUG : Creating backend with remote "/tmp/rclone1247409850"
=== RUN   TestApplyTransforms
2025/07/31 02:35:49 DEBUG : Creating backend with remote "TestDropbox:rclone-test-rixezos6goba"
2025/07/31 02:35:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:35:49 DEBUG : Creating backend with remote "/tmp/rclone1548218205"
    run.go:180: Remote "Dropbox root 'rclone-test-rixezos6goba'", Local "Local file system at /tmp/rclone1548218205", Modify Window "1s"
2025/07/31 02:35:50 DEBUG : hello, world!: Uploading chunk 1/1
2025/07/31 02:35:51 DEBUG : hello, world!: Uploading chunk 2/1
2025/07/31 02:35:51 DEBUG : Dropbox root 'rclone-test-rixezos6goba': Adding "hello, world!" to batch
2025/07/31 02:35:52 DEBUG : Dropbox root 'rclone-test-rixezos6goba': Batch idle for 500ms so committing
2025/07/31 02:35:52 DEBUG : Dropbox root 'rclone-test-rixezos6goba': Committing sync batch length 1 starting with: hello, world!
2025/07/31 02:35:52 DEBUG : Dropbox root 'rclone-test-rixezos6goba': Committed sync batch length 1 starting with: hello, world!
upper checkfile vs. lower remote (without normalization)
2025/07/31 02:35:53 ERROR : hello, world!: sum not found
2025/07/31 02:35:53 ERROR : HELLO, WORLD!: file not in Dropbox root 'rclone-test-rixezos6goba'
2025/07/31 02:35:53 NOTICE: Dropbox root 'rclone-test-rixezos6goba': 1 files missing
2025/07/31 02:35:53 NOTICE: 1 hashes missing
2025/07/31 02:35:53 NOTICE: Dropbox root 'rclone-test-rixezos6goba': 1 differences found
2025/07/31 02:35:53 NOTICE: Dropbox root 'rclone-test-rixezos6goba': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2025/07/31 02:35:54 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/07/31 02:35:54 NOTICE: Dropbox root 'rclone-test-rixezos6goba': 0 differences found
2025/07/31 02:35:54 NOTICE: Dropbox root 'rclone-test-rixezos6goba': 1 matching files
2025/07/31 02:35:54 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cijapih7wici"
2025/07/31 02:35:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:35:54 DEBUG : Creating backend with remote "/tmp/rclone4215991645"
    run.go:180: Remote "Dropbox root 'rclone-test-cijapih7wici'", Local "Local file system at /tmp/rclone4215991645", Modify Window "1s"
2025/07/31 02:35:55 DEBUG : HELLO, WORLD!: Uploading chunk 1/1
2025/07/31 02:35:56 DEBUG : HELLO, WORLD!: Uploading chunk 2/1
2025/07/31 02:35:56 DEBUG : Dropbox root 'rclone-test-cijapih7wici': Adding "HELLO, WORLD!" to batch
2025/07/31 02:35:57 DEBUG : Dropbox root 'rclone-test-cijapih7wici': Batch idle for 500ms so committing
2025/07/31 02:35:57 DEBUG : Dropbox root 'rclone-test-cijapih7wici': Committing sync batch length 1 starting with: HELLO, WORLD!
2025/07/31 02:35:57 DEBUG : Dropbox root 'rclone-test-cijapih7wici': Committed sync batch length 1 starting with: HELLO, WORLD!
lower checkfile vs. upper remote (without normalization)
2025/07/31 02:35:58 ERROR : HELLO, WORLD!: sum not found
2025/07/31 02:35:58 ERROR : hello, world!: file not in Dropbox root 'rclone-test-cijapih7wici'
2025/07/31 02:35:58 NOTICE: Dropbox root 'rclone-test-cijapih7wici': 1 files missing
2025/07/31 02:35:58 NOTICE: 1 hashes missing
2025/07/31 02:35:58 NOTICE: Dropbox root 'rclone-test-cijapih7wici': 1 differences found
2025/07/31 02:35:58 NOTICE: Dropbox root 'rclone-test-cijapih7wici': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2025/07/31 02:35:59 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/07/31 02:35:59 NOTICE: Dropbox root 'rclone-test-cijapih7wici': 0 differences found
2025/07/31 02:35:59 NOTICE: Dropbox root 'rclone-test-cijapih7wici': 1 matching files
2025/07/31 02:35:59 DEBUG : Creating backend with remote "TestDropbox:rclone-test-jinapab7yafu"
2025/07/31 02:35:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:35:59 DEBUG : Creating backend with remote "/tmp/rclone1536971377"
    run.go:180: Remote "Dropbox root 'rclone-test-jinapab7yafu'", Local "Local file system at /tmp/rclone1536971377", Modify Window "1s"
2025/07/31 02:36:00 DEBUG : HeLlO, wOrLd!: Uploading chunk 1/1
2025/07/31 02:36:01 DEBUG : HeLlO, wOrLd!: Uploading chunk 2/1
2025/07/31 02:36:01 DEBUG : Dropbox root 'rclone-test-jinapab7yafu': Adding "HeLlO, wOrLd!" to batch
2025/07/31 02:36:02 DEBUG : Dropbox root 'rclone-test-jinapab7yafu': Batch idle for 500ms so committing
2025/07/31 02:36:02 DEBUG : Dropbox root 'rclone-test-jinapab7yafu': Committing sync batch length 1 starting with: HeLlO, wOrLd!
2025/07/31 02:36:03 DEBUG : Dropbox root 'rclone-test-jinapab7yafu': Committed sync batch length 1 starting with: HeLlO, wOrLd!
lower checkfile vs. upperlowermixed remote (without normalization)
2025/07/31 02:36:03 ERROR : HeLlO, wOrLd!: sum not found
2025/07/31 02:36:03 ERROR : hello, world!: file not in Dropbox root 'rclone-test-jinapab7yafu'
2025/07/31 02:36:03 NOTICE: Dropbox root 'rclone-test-jinapab7yafu': 1 files missing
2025/07/31 02:36:03 NOTICE: 1 hashes missing
2025/07/31 02:36:03 NOTICE: Dropbox root 'rclone-test-jinapab7yafu': 1 differences found
2025/07/31 02:36:03 NOTICE: Dropbox root 'rclone-test-jinapab7yafu': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2025/07/31 02:36:04 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/07/31 02:36:04 NOTICE: Dropbox root 'rclone-test-jinapab7yafu': 0 differences found
2025/07/31 02:36:04 NOTICE: Dropbox root 'rclone-test-jinapab7yafu': 1 matching files
2025/07/31 02:36:04 DEBUG : Creating backend with remote "TestDropbox:rclone-test-biwuwec9xigo"
2025/07/31 02:36:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:36:04 DEBUG : Creating backend with remote "/tmp/rclone1320521416"
    run.go:180: Remote "Dropbox root 'rclone-test-biwuwec9xigo'", Local "Local file system at /tmp/rclone1320521416", Modify Window "1s"
2025/07/31 02:36:06 DEBUG : HELLO, WORLD!: Uploading chunk 1/1
2025/07/31 02:36:07 DEBUG : HELLO, WORLD!: Uploading chunk 2/1
2025/07/31 02:36:07 DEBUG : Dropbox root 'rclone-test-biwuwec9xigo': Adding "HELLO, WORLD!" to batch
2025/07/31 02:36:08 DEBUG : Dropbox root 'rclone-test-biwuwec9xigo': Batch idle for 500ms so committing
2025/07/31 02:36:08 DEBUG : Dropbox root 'rclone-test-biwuwec9xigo': Committing sync batch length 1 starting with: HELLO, WORLD!
2025/07/31 02:36:09 DEBUG : Dropbox root 'rclone-test-biwuwec9xigo': Committed sync batch length 1 starting with: HELLO, WORLD!
upperlowermixed checkfile vs. upper remote (without normalization)
2025/07/31 02:36:09 ERROR : HELLO, WORLD!: sum not found
2025/07/31 02:36:09 ERROR : HeLlO, wOrLd!: file not in Dropbox root 'rclone-test-biwuwec9xigo'
2025/07/31 02:36:09 NOTICE: Dropbox root 'rclone-test-biwuwec9xigo': 1 files missing
2025/07/31 02:36:09 NOTICE: 1 hashes missing
2025/07/31 02:36:09 NOTICE: Dropbox root 'rclone-test-biwuwec9xigo': 1 differences found
2025/07/31 02:36:09 NOTICE: Dropbox root 'rclone-test-biwuwec9xigo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2025/07/31 02:36:10 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/07/31 02:36:10 NOTICE: Dropbox root 'rclone-test-biwuwec9xigo': 0 differences found
2025/07/31 02:36:10 NOTICE: Dropbox root 'rclone-test-biwuwec9xigo': 1 matching files
2025/07/31 02:36:10 DEBUG : Creating backend with remote "TestDropbox:rclone-test-pupojar6qiqi"
2025/07/31 02:36:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:36:11 DEBUG : Creating backend with remote "/tmp/rclone3636681150"
    run.go:180: Remote "Dropbox root 'rclone-test-pupojar6qiqi'", Local "Local file system at /tmp/rclone3636681150", Modify Window "1s"
2025/07/31 02:36:11 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/07/31 02:36:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/07/31 02:36:12 DEBUG : pacer: Reducing sleep to 15ms
2025/07/31 02:36:12 DEBUG : pacer: Reducing sleep to 11.25ms
2025/07/31 02:36:12 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2025/07/31 02:36:13 DEBUG : pacer: Reducing sleep to 10ms
2025/07/31 02:36:13 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2025/07/31 02:36:13 DEBUG : Dropbox root 'rclone-test-pupojar6qiqi': Adding "測試_Русский___ě_áñ" to batch
2025/07/31 02:36:14 DEBUG : Dropbox root 'rclone-test-pupojar6qiqi': Batch idle for 500ms so committing
2025/07/31 02:36:14 DEBUG : Dropbox root 'rclone-test-pupojar6qiqi': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/07/31 02:36:14 DEBUG : Dropbox root 'rclone-test-pupojar6qiqi': Committed sync batch length 1 starting with: 測試_Русский___ě_áñ
NFD checkfile vs. NFC remote (without normalization)
2025/07/31 02:36:15 ERROR : 測試_Русский___ě_áñ: sum not found
2025/07/31 02:36:15 ERROR : 測試_Русский___ě_áñ: file not in Dropbox root 'rclone-test-pupojar6qiqi'
2025/07/31 02:36:15 NOTICE: Dropbox root 'rclone-test-pupojar6qiqi': 1 files missing
2025/07/31 02:36:15 NOTICE: 1 hashes missing
2025/07/31 02:36:15 NOTICE: Dropbox root 'rclone-test-pupojar6qiqi': 1 differences found
2025/07/31 02:36:15 NOTICE: Dropbox root 'rclone-test-pupojar6qiqi': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2025/07/31 02:36:16 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/07/31 02:36:16 NOTICE: Dropbox root 'rclone-test-pupojar6qiqi': 0 differences found
2025/07/31 02:36:16 NOTICE: Dropbox root 'rclone-test-pupojar6qiqi': 1 matching files
2025/07/31 02:36:16 DEBUG : Creating backend with remote "TestDropbox:rclone-test-dedesus0cija"
2025/07/31 02:36:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/31 02:36:16 DEBUG : Creating backend with remote "/tmp/rclone3274640115"
    run.go:180: Remote "Dropbox root 'rclone-test-dedesus0cija'", Local "Local file system at /tmp/rclone3274640115", Modify Window "1s"
2025/07/31 02:36:18 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2025/07/31 02:36:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/07/31 02:36:18 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/07/31 02:36:18 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/07/31 02:36:18 DEBUG : pacer: Reducing sleep to 30ms
2025/07/31 02:36:19 DEBUG : pacer: Reducing sleep to 22.5ms
2025/07/31 02:36:19 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2025/07/31 02:36:19 DEBUG : pacer: Reducing sleep to 16.875ms
2025/07/31 02:36:19 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2025/07/31 02:36:20 DEBUG : pacer: Reducing sleep to 12.65625ms
2025/07/31 02:36:20 DEBUG : Dropbox root 'rclone-test-dedesus0cija': Adding "測試_Русский___ě_áñ" to batch
2025/07/31 02:36:20 DEBUG : Dropbox root 'rclone-test-dedesus0cija': Batch idle for 500ms so committing
2025/07/31 02:36:20 DEBUG : Dropbox root 'rclone-test-dedesus0cija': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/07/31 02:36:21 DEBUG : pacer: Reducing sleep to 10ms
2025/07/31 02:36:21 DEBUG : Dropbox root 'rclone-test-dedesus0cija': 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/07/31 02:36:21 DEBUG : Dropbox root 'rclone-test-dedesus0cija': Purge remote
2025/07/31 02:36:22 DEBUG : Dropbox root 'rclone-test-pupojar6qiqi': Purge remote
2025/07/31 02:36:23 DEBUG : Dropbox root 'rclone-test-biwuwec9xigo': Purge remote
2025/07/31 02:36:23 DEBUG : Dropbox root 'rclone-test-jinapab7yafu': Purge remote
2025/07/31 02:36:24 DEBUG : Dropbox root 'rclone-test-cijapih7wici': Purge remote
2025/07/31 02:36:24 DEBUG : Dropbox root 'rclone-test-rixezos6goba': Purge remote
--- SKIP: TestApplyTransforms (36.51s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:180: Remote "Dropbox root 'rclone-test-quzefit1codo'", Local "Local file system at /tmp/rclone1247409850", Modify Window "1s"
2025/07/31 02:36:25 ERROR : error listing: directory not found
2025/07/31 02:36:25 DEBUG : Dropbox root 'rclone-test-quzefit1codo': File to upload is small (34 bytes), uploading instead of streaming
2025/07/31 02:36:26 DEBUG : with_checksum_small_file_from_pipe: Uploading chunk 1/1
2025/07/31 02:36:26 DEBUG : with_checksum_small_file_from_pipe: Uploading chunk 2/1
2025/07/31 02:36:27 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Adding "with_checksum_small_file_from_pipe" to batch
2025/07/31 02:36:27 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Batch idle for 500ms so committing
2025/07/31 02:36:27 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Committing sync batch length 1 starting with: with_checksum_small_file_from_pipe
2025/07/31 02:36:29 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Committed sync batch length 1 starting with: with_checksum_small_file_from_pipe
2025/07/31 02:36:29 DEBUG : with_checksum_small_file_from_pipe: dropbox = 6167057cad26d021a22944146dfef827ec973d33996a25ece54f17508427cf5f OK
2025/07/31 02:36:29 DEBUG : with_checksum_small_file_from_pipe: Size and dropbox of src and dst objects identical
2025/07/31 02:36:29 DEBUG : with_checksum_big_file_from_pipe: Uploading chunk 1/0
2025/07/31 02:36:30 DEBUG : with_checksum_big_file_from_pipe: Uploading chunk 2/0
2025/07/31 02:36:30 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Adding "with_checksum_big_file_from_pipe" to batch
2025/07/31 02:36:31 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Batch idle for 500ms so committing
2025/07/31 02:36:31 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Committing sync batch length 1 starting with: with_checksum_big_file_from_pipe
2025/07/31 02:36:31 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Committed sync batch length 1 starting with: with_checksum_big_file_from_pipe
2025/07/31 02:36:31 DEBUG : with_checksum_big_file_from_pipe: dropbox = 134292f7f01dbc9fa2e8230e98def0b183c01ca9fce46a144f90bee4ef0c4ef3 OK
2025/07/31 02:36:31 DEBUG : with_checksum_big_file_from_pipe: Size and dropbox of src and dst objects identical
--- PASS: TestRcat (9.77s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (9.77s)
PASS
2025/07/31 02:36:35 DEBUG : Dropbox root 'rclone-test-quzefit1codo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestApplyTransforms$|^TestRcat$/^withChecksum=true,ignoreChecksum=false$'" - Finished OK in 48.046212423s (try 2/5)