"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCheckDownload|TestCheckSizeOnly|TestCopyFileCopyDest|TestCopyURL|TestListDirSorted|TestMaxDeleteSizeLargeFile|TestMoveFileBackupDir|TestRcatSize|TestStatJSON)$|^TestRcat$/^withChecksum=true,ignoreChecksum=true$'" - Starting (try 2/5)
2025/07/07 01:29:42 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yocaloh7romo"
2025/07/07 01:29:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/07 01:29:42 DEBUG : Creating backend with remote "/tmp/rclone1053471379"
=== RUN   TestCheckDownload
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:29:43 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/07/07 01:29:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/07/07 01:29:43 DEBUG : pacer: Reducing sleep to 15ms
2025/07/07 01:29:44 DEBUG : pacer: Reducing sleep to 11.25ms
2025/07/07 01:29:44 DEBUG : rutabaga: Uploading chunk 1/1
2025/07/07 01:29:44 DEBUG : pacer: Reducing sleep to 10ms
2025/07/07 01:29:44 DEBUG : rutabaga: Uploading chunk 2/1
2025/07/07 01:29:45 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "rutabaga" to batch
2025/07/07 01:29:45 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:29:45 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: rutabaga
2025/07/07 01:29:46 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: rutabaga
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2025/07/07 01:29:48 DEBUG : empty space: Uploading chunk 1/1
2025/07/07 01:29:48 DEBUG : empty space: Uploading chunk 2/1
2025/07/07 01:29:49 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "empty space" to batch
2025/07/07 01:29:49 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:29:49 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: empty space
2025/07/07 01:29:50 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: empty space
=== RUN   TestCheckDownload/3
2025/07/07 01:29:51 DEBUG : potato2: Uploading chunk 1/1
2025/07/07 01:29:51 DEBUG : potato2: Uploading chunk 2/1
2025/07/07 01:29:52 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "potato2" to batch
2025/07/07 01:29:52 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:29:52 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: potato2
2025/07/07 01:29:53 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: potato2
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2025/07/07 01:29:56 DEBUG : remotepotato: Uploading chunk 1/1
2025/07/07 01:29:56 DEBUG : remotepotato: Uploading chunk 2/1
2025/07/07 01:29:56 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "remotepotato" to batch
2025/07/07 01:29:57 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:29:57 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: remotepotato
2025/07/07 01:29:58 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: remotepotato
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (20.07s)
    --- PASS: TestCheckDownload/1 (0.62s)
    --- PASS: TestCheckDownload/2 (0.73s)
    --- PASS: TestCheckDownload/3 (0.68s)
    --- PASS: TestCheckDownload/4 (1.00s)
    --- PASS: TestCheckDownload/5 (0.90s)
    --- PASS: TestCheckDownload/6 (0.86s)
    --- PASS: TestCheckDownload/7 (0.85s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:30:03 DEBUG : rutabaga: Uploading chunk 1/1
2025/07/07 01:30:03 DEBUG : rutabaga: Uploading chunk 2/1
2025/07/07 01:30:04 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "rutabaga" to batch
2025/07/07 01:30:04 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:04 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: rutabaga
2025/07/07 01:30:05 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: rutabaga
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2025/07/07 01:30:06 DEBUG : empty space: Uploading chunk 1/1
2025/07/07 01:30:07 DEBUG : empty space: Uploading chunk 2/1
2025/07/07 01:30:07 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "empty space" to batch
2025/07/07 01:30:08 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:08 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: empty space
2025/07/07 01:30:09 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: empty space
=== RUN   TestCheckSizeOnly/3
2025/07/07 01:30:09 DEBUG : potato2: Uploading chunk 1/1
2025/07/07 01:30:10 DEBUG : potato2: Uploading chunk 2/1
2025/07/07 01:30:10 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "potato2" to batch
2025/07/07 01:30:11 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:11 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: potato2
2025/07/07 01:30:11 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: potato2
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2025/07/07 01:30:12 DEBUG : remotepotato: Uploading chunk 1/1
2025/07/07 01:30:13 DEBUG : remotepotato: Uploading chunk 2/1
2025/07/07 01:30:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "remotepotato" to batch
2025/07/07 01:30:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: remotepotato
2025/07/07 01:30:15 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: remotepotato
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (15.86s)
    --- PASS: TestCheckSizeOnly/1 (0.21s)
    --- PASS: TestCheckSizeOnly/2 (0.23s)
    --- PASS: TestCheckSizeOnly/3 (0.21s)
    --- PASS: TestCheckSizeOnly/4 (0.20s)
    --- PASS: TestCheckSizeOnly/5 (0.23s)
    --- PASS: TestCheckSizeOnly/6 (0.26s)
    --- PASS: TestCheckSizeOnly/7 (0.21s)
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:30:18 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yocaloh7romo/dst"
2025/07/07 01:30:19 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yocaloh7romo/CopyDest"
2025/07/07 01:30:19 DEBUG : one: Need to transfer - File not found at Destination
2025/07/07 01:30:20 DEBUG : one: Uploading chunk 1/1
2025/07/07 01:30:20 DEBUG : one: Uploading chunk 2/1
2025/07/07 01:30:20 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Adding "one" to batch
2025/07/07 01:30:21 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Batch idle for 500ms so committing
2025/07/07 01:30:21 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Committing sync batch length 1 starting with: one
2025/07/07 01:30:22 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Committed sync batch length 1 starting with: one
2025/07/07 01:30:22 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK
2025/07/07 01:30:22 INFO  : one: Copied (new)
2025/07/07 01:30:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/07 01:30:24 DEBUG : one: Uploading chunk 1/1
2025/07/07 01:30:24 DEBUG : one: Uploading chunk 2/1
2025/07/07 01:30:24 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Adding "one" to batch
2025/07/07 01:30:25 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Batch idle for 500ms so committing
2025/07/07 01:30:25 DEBUG : Dropbox root 'rclone-test-yocaloh7romo/dst': Committing sync batch length 1 starting with: one
2025/07/07 01:30:27 ERROR : Dropbox root 'rclone-test-yocaloh7romo/dst': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
2025/07/07 01:30:27 ERROR : one: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations
    copy_test.go:302: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:302
        	Error:      	Received unexpected error:
        	            	upload failed: batch upload failed: upload failed: too_many_write_operations
        	Test:       	TestCopyFileCopyDest
2025/07/07 01:30:29 INFO  : Dropbox root 'rclone-test-yocaloh7romo/CopyDest': Committing uploads - please wait...
--- FAIL: TestCopyFileCopyDest (11.08s)
=== RUN   TestListDirSorted
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:30:30 DEBUG : a.txt: Uploading chunk 1/1
2025/07/07 01:30:30 DEBUG : a.txt: Uploading chunk 2/1
2025/07/07 01:30:30 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "a.txt" to batch
2025/07/07 01:30:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: a.txt
2025/07/07 01:30:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: a.txt
2025/07/07 01:30:32 DEBUG : zend.txt: Uploading chunk 1/1
2025/07/07 01:30:32 DEBUG : zend.txt: Uploading chunk 2/1
2025/07/07 01:30:32 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "zend.txt" to batch
2025/07/07 01:30:33 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:33 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: zend.txt
2025/07/07 01:30:33 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: zend.txt
2025/07/07 01:30:34 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/07/07 01:30:34 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/07/07 01:30:34 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub dir/hello world" to batch
2025/07/07 01:30:35 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:35 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub dir/hello world
2025/07/07 01:30:36 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub dir/hello world
2025/07/07 01:30:36 DEBUG : sub dir/hello world2: Uploading chunk 1/1
2025/07/07 01:30:36 DEBUG : sub dir/hello world2: Uploading chunk 2/1
2025/07/07 01:30:37 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub dir/hello world2" to batch
2025/07/07 01:30:37 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:37 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub dir/hello world2
2025/07/07 01:30:38 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub dir/hello world2
2025/07/07 01:30:38 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 1/1
2025/07/07 01:30:39 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 2/1
2025/07/07 01:30:39 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub dir/ignore dir/.ignore" to batch
2025/07/07 01:30:40 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:40 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/07/07 01:30:42 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/07/07 01:30:42 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 1/1
2025/07/07 01:30:42 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 2/1
2025/07/07 01:30:43 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub dir/ignore dir/should be ignored" to batch
2025/07/07 01:30:43 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:43 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/07/07 01:30:44 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/07/07 01:30:44 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 1/1
2025/07/07 01:30:44 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 2/1
2025/07/07 01:30:44 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub dir/sub sub dir/hello world3" to batch
2025/07/07 01:30:45 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:30:45 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/07/07 01:30:46 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/07/07 01:30:47 DEBUG : a.txt: Excluded (Size Filter)
2025/07/07 01:30:47 DEBUG : a.txt: Excluded
2025/07/07 01:30:47 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/07/07 01:30:47 DEBUG : sub dir/hello world: Excluded
2025/07/07 01:30:47 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/07/07 01:30:47 DEBUG : sub dir/hello world2: Excluded
2025/07/07 01:30:48 DEBUG : sub dir/ignore dir: Excluded
2025/07/07 01:30:48 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/07/07 01:30:48 DEBUG : sub dir/hello world: Excluded
2025/07/07 01:30:48 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/07/07 01:30:48 DEBUG : sub dir/hello world2: Excluded
2025/07/07 01:30:48 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (28.99s)
=== RUN   TestStatJSON
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:30:59 DEBUG : file1: Uploading chunk 1/1
2025/07/07 01:30:59 DEBUG : file1: Uploading chunk 2/1
2025/07/07 01:30:59 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "file1" to batch
2025/07/07 01:31:00 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:00 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: file1
2025/07/07 01:31:01 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: file1
2025/07/07 01:31:01 DEBUG : sub/file2: Uploading chunk 1/1
2025/07/07 01:31:01 DEBUG : sub/file2: Uploading chunk 2/1
2025/07/07 01:31:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "sub/file2" to batch
2025/07/07 01:31:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: sub/file2
2025/07/07 01:31:03 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: sub/file2
=== RUN   TestStatJSON/Root
=== RUN   TestStatJSON/RootFilesOnly
=== RUN   TestStatJSON/RootDirsOnly
=== RUN   TestStatJSON/Dir
=== RUN   TestStatJSON/DirWithTrailingSlash
=== RUN   TestStatJSON/File
=== RUN   TestStatJSON/NotFound
=== RUN   TestStatJSON/DirFilesOnly
=== RUN   TestStatJSON/FileFilesOnly
=== RUN   TestStatJSON/NotFoundFilesOnly
=== RUN   TestStatJSON/DirDirsOnly
=== RUN   TestStatJSON/FileDirsOnly
=== RUN   TestStatJSON/NotFoundDirsOnly
=== RUN   TestStatJSON/RootNotFound
2025/07/07 01:31:07 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yocaloh7romo/notfound"
2025/07/07 01:31:07 DEBUG : Config file has changed externally - reloading
--- PASS: TestStatJSON (13.44s)
    --- PASS: TestStatJSON/Root (0.24s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.24s)
    --- PASS: TestStatJSON/Dir (0.52s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.24s)
    --- PASS: TestStatJSON/File (0.35s)
    --- PASS: TestStatJSON/NotFound (0.43s)
    --- PASS: TestStatJSON/DirFilesOnly (0.27s)
    --- PASS: TestStatJSON/FileFilesOnly (0.29s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.19s)
    --- PASS: TestStatJSON/DirDirsOnly (0.23s)
    --- PASS: TestStatJSON/FileDirsOnly (0.24s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.24s)
    --- PASS: TestStatJSON/RootNotFound (0.48s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:31:12 DEBUG : small: Uploading chunk 1/1
2025/07/07 01:31:12 DEBUG : small: Uploading chunk 2/1
2025/07/07 01:31:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "small" to batch
2025/07/07 01:31:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:13 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: small
2025/07/07 01:31:14 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: small
2025/07/07 01:31:15 DEBUG : medium: Uploading chunk 1/1
2025/07/07 01:31:15 DEBUG : medium: Uploading chunk 2/1
2025/07/07 01:31:15 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "medium" to batch
2025/07/07 01:31:16 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:16 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: medium
2025/07/07 01:31:16 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: medium
2025/07/07 01:31:17 DEBUG : large: Uploading chunk 1/1
2025/07/07 01:31:17 DEBUG : large: Uploading chunk 2/1
2025/07/07 01:31:18 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "large" to batch
2025/07/07 01:31:18 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:18 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: large
2025/07/07 01:31:19 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: large
2025/07/07 01:31:19 DEBUG : Waiting for deletions to finish
2025/07/07 01:31:19 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2025/07/07 01:31:20 INFO  : medium: Deleted
2025/07/07 01:31:20 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (9.92s)
=== RUN   TestCopyURL
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:31:22 DEBUG : file1: Uploading chunk 1/1
2025/07/07 01:31:23 DEBUG : file1: Uploading chunk 2/1
2025/07/07 01:31:23 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "file1" to batch
2025/07/07 01:31:23 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:23 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: file1
2025/07/07 01:31:24 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: file1
2025/07/07 01:31:25 DEBUG : filename.txt: File name found in url
2025/07/07 01:31:25 DEBUG : filename.txt: Uploading chunk 1/1
2025/07/07 01:31:25 DEBUG : filename.txt: Uploading chunk 2/1
2025/07/07 01:31:26 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "filename.txt" to batch
2025/07/07 01:31:26 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:26 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: filename.txt
2025/07/07 01:31:27 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: filename.txt
2025/07/07 01:31:27 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2025/07/07 01:31:27 DEBUG : headerfilename.txt: Uploading chunk 1/1
2025/07/07 01:31:28 DEBUG : headerfilename.txt: Uploading chunk 2/1
2025/07/07 01:31:28 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "headerfilename.txt" to batch
2025/07/07 01:31:29 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:29 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: headerfilename.txt
2025/07/07 01:31:30 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: headerfilename.txt
2025/07/07 01:31:30 DEBUG : file2: Uploading chunk 1/1
2025/07/07 01:31:30 DEBUG : file2: Uploading chunk 2/1
2025/07/07 01:31:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "file2" to batch
2025/07/07 01:31:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:31 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: file2
2025/07/07 01:31:32 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: file2
--- PASS: TestCopyURL (13.39s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:31:35 DEBUG : dst/file1: Uploading chunk 1/1
2025/07/07 01:31:36 DEBUG : dst/file1: Uploading chunk 2/1
2025/07/07 01:31:36 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "dst/file1" to batch
2025/07/07 01:31:36 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:36 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: dst/file1
2025/07/07 01:31:38 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: dst/file1
2025/07/07 01:31:39 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yocaloh7romo/backup"
2025/07/07 01:31:39 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/07/07 01:31:40 DEBUG : pacer: low level retry 1/10 (error from_write/too_many_write_operations/.)
2025/07/07 01:31:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/07/07 01:31:41 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.)
2025/07/07 01:31:41 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/07/07 01:31:42 DEBUG : pacer: Reducing sleep to 30ms
2025/07/07 01:31:42 INFO  : dst/file1: Moved (server-side)
2025/07/07 01:31:42 DEBUG : dst/file1: Uploading chunk 1/1
2025/07/07 01:31:42 DEBUG : dst/file1: Uploading chunk 2/1
2025/07/07 01:31:43 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "dst/file1" to batch
2025/07/07 01:31:44 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:44 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: dst/file1
2025/07/07 01:31:44 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: dst/file1
2025/07/07 01:31:44 DEBUG : dst/file1: dropbox = d4b8d7e13c592e454a060a60b4606d5e5c5a03b023d97e24bf4682e9535a8778 OK
2025/07/07 01:31:44 INFO  : dst/file1: Copied (new)
2025/07/07 01:31:44 INFO  : dst/file1: Deleted
2025/07/07 01:31:52 INFO  : Dropbox root 'rclone-test-yocaloh7romo/backup': Committing uploads - please wait...
--- PASS: TestMoveFileBackupDir (16.46s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:31:52 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': File to upload is small (34 bytes), uploading instead of streaming
2025/07/07 01:31:52 DEBUG : ignore_checksum_small_file_from_pipe: Uploading chunk 1/1
2025/07/07 01:31:52 DEBUG : ignore_checksum_small_file_from_pipe: Uploading chunk 2/1
2025/07/07 01:31:53 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "ignore_checksum_small_file_from_pipe" to batch
2025/07/07 01:31:53 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:53 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: ignore_checksum_small_file_from_pipe
2025/07/07 01:31:54 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: ignore_checksum_small_file_from_pipe
2025/07/07 01:31:54 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/07/07 01:31:54 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/07/07 01:31:54 DEBUG : ignore_checksum_big_file_from_pipe: Uploading chunk 1/0
2025/07/07 01:31:55 DEBUG : ignore_checksum_big_file_from_pipe: Uploading chunk 2/0
2025/07/07 01:31:55 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "ignore_checksum_big_file_from_pipe" to batch
2025/07/07 01:31:56 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:31:56 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: ignore_checksum_big_file_from_pipe
2025/07/07 01:31:56 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: ignore_checksum_big_file_from_pipe
2025/07/07 01:31:56 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/07/07 01:31:56 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (6.60s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (6.60s)
=== RUN   TestRcatSize
    run.go:180: Remote "Dropbox root 'rclone-test-yocaloh7romo'", Local "Local file system at /tmp/rclone1053471379", Modify Window "1s"
2025/07/07 01:31:58 DEBUG : potato1: Uploading chunk 1/1
2025/07/07 01:31:59 DEBUG : potato1: Uploading chunk 2/1
2025/07/07 01:31:59 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "potato1" to batch
2025/07/07 01:32:00 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:32:00 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: potato1
2025/07/07 01:32:01 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: potato1
2025/07/07 01:32:01 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': File to upload is small (60 bytes), uploading instead of streaming
2025/07/07 01:32:01 DEBUG : potato2: Uploading chunk 1/1
2025/07/07 01:32:01 DEBUG : potato2: Uploading chunk 2/1
2025/07/07 01:32:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Adding "potato2" to batch
2025/07/07 01:32:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Batch idle for 500ms so committing
2025/07/07 01:32:02 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committing sync batch length 1 starting with: potato2
2025/07/07 01:32:03 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Committed sync batch length 1 starting with: potato2
2025/07/07 01:32:03 DEBUG : potato2: dropbox = a979481df794fed9c3990a6a422e0b1044ac802c15fab13af9c687f8bdbee01a OK
2025/07/07 01:32:03 DEBUG : potato2: Size and dropbox of src and dst objects identical
--- PASS: TestRcatSize (7.12s)
FAIL
2025/07/07 01:32:05 DEBUG : Dropbox root 'rclone-test-yocaloh7romo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCheckDownload|TestCheckSizeOnly|TestCopyFileCopyDest|TestCopyURL|TestListDirSorted|TestMaxDeleteSizeLargeFile|TestMoveFileBackupDir|TestRcatSize|TestStatJSON)$|^TestRcat$/^withChecksum=true,ignoreChecksum=true$'" - Finished ERROR in 2m24.013863962s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest]