"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCat|TestCopyURL|TestCount|TestDeduplicateNewestByHash|TestListDirSortedFn|TestListJSON)$'" - Starting (try 2/5)
2025/10/02 03:27:20 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wapisay1rote"
2025/10/02 03:27:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/02 03:27:20 DEBUG : Creating backend with remote "/tmp/rclone3363201560"
=== RUN   TestDeduplicateNewestByHash
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:27:21 DEBUG : one: Uploading chunk 1/1
2025/10/02 03:27:22 DEBUG : one: Uploading chunk 2/1
2025/10/02 03:27:22 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "one" to batch
2025/10/02 03:27:23 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:23 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: one
2025/10/02 03:27:24 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: one
2025/10/02 03:27:24 DEBUG : also/one: Uploading chunk 1/1
2025/10/02 03:27:24 DEBUG : also/one: Uploading chunk 2/1
2025/10/02 03:27:25 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "also/one" to batch
2025/10/02 03:27:25 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:25 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: also/one
2025/10/02 03:27:26 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: also/one
2025/10/02 03:27:27 DEBUG : another: Uploading chunk 1/1
2025/10/02 03:27:27 DEBUG : another: Uploading chunk 2/1
2025/10/02 03:27:27 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "another" to batch
2025/10/02 03:27:28 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:28 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: another
2025/10/02 03:27:29 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: another
2025/10/02 03:27:29 DEBUG : not-one: Uploading chunk 1/1
2025/10/02 03:27:29 DEBUG : not-one: Uploading chunk 2/1
2025/10/02 03:27:30 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "not-one" to batch
2025/10/02 03:27:30 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:30 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: not-one
2025/10/02 03:27:32 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: not-one
2025/10/02 03:27:32 INFO  : Dropbox root 'rclone-test-wapisay1rote': Looking for duplicate dropbox hashes using newest mode.
2025/10/02 03:27:33 NOTICE: 3b9efc0e6dd892eb50e764f314e6a4c835afb76ddfd89fc9d213cd851f4933af: Found 3 files with duplicate dropbox hashes
2025/10/02 03:27:33 INFO  : one: Deleted
2025/10/02 03:27:34 INFO  : also/one: Deleted
2025/10/02 03:27:34 NOTICE: 3b9efc0e6dd892eb50e764f314e6a4c835afb76ddfd89fc9d213cd851f4933af: Deleted 2 extra copies
--- PASS: TestDeduplicateNewestByHash (17.02s)
=== RUN   TestListDirSortedFn
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:27:38 DEBUG : a.txt: Uploading chunk 1/1
2025/10/02 03:27:38 DEBUG : a.txt: Uploading chunk 2/1
2025/10/02 03:27:38 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "a.txt" to batch
2025/10/02 03:27:39 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:39 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: a.txt
2025/10/02 03:27:39 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: a.txt
2025/10/02 03:27:40 DEBUG : zend.txt: Uploading chunk 1/1
2025/10/02 03:27:40 DEBUG : zend.txt: Uploading chunk 2/1
2025/10/02 03:27:40 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "zend.txt" to batch
2025/10/02 03:27:41 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:41 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: zend.txt
2025/10/02 03:27:41 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: zend.txt
2025/10/02 03:27:42 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/10/02 03:27:42 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/10/02 03:27:42 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/hello world" to batch
2025/10/02 03:27:43 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:43 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/hello world
2025/10/02 03:27:43 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/hello world
2025/10/02 03:27:44 DEBUG : sub dir/hello world2: Uploading chunk 1/1
2025/10/02 03:27:44 DEBUG : sub dir/hello world2: Uploading chunk 2/1
2025/10/02 03:27:45 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/hello world2" to batch
2025/10/02 03:27:45 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:45 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/hello world2
2025/10/02 03:27:46 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/hello world2
2025/10/02 03:27:46 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 1/1
2025/10/02 03:27:46 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 2/1
2025/10/02 03:27:47 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/ignore dir/.ignore" to batch
2025/10/02 03:27:48 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:48 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/10/02 03:27:48 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/10/02 03:27:49 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 1/1
2025/10/02 03:27:49 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 2/1
2025/10/02 03:27:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/ignore dir/should be ignored" to batch
2025/10/02 03:27:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/10/02 03:27:51 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/10/02 03:27:51 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 1/1
2025/10/02 03:27:51 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 2/1
2025/10/02 03:27:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/sub sub dir/hello world3" to batch
2025/10/02 03:27:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:27:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/10/02 03:27:54 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/10/02 03:27:55 DEBUG : a.txt: Excluded (Size Filter)
2025/10/02 03:27:55 DEBUG : a.txt: Excluded
2025/10/02 03:27:55 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/10/02 03:27:55 DEBUG : sub dir/hello world: Excluded
2025/10/02 03:27:55 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/10/02 03:27:55 DEBUG : sub dir/hello world2: Excluded
2025/10/02 03:27:56 DEBUG : sub dir/ignore dir: Excluded
2025/10/02 03:27:56 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/10/02 03:27:56 DEBUG : sub dir/hello world: Excluded
2025/10/02 03:27:56 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/10/02 03:27:56 DEBUG : sub dir/hello world2: Excluded
2025/10/02 03:27:56 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (28.20s)
=== RUN   TestListJSON
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:28:06 DEBUG : file1: Uploading chunk 1/1
2025/10/02 03:28:06 DEBUG : file1: Uploading chunk 2/1
2025/10/02 03:28:06 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "file1" to batch
2025/10/02 03:28:07 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:07 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: file1
2025/10/02 03:28:08 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: file1
2025/10/02 03:28:09 DEBUG : sub/file2: Uploading chunk 1/1
2025/10/02 03:28:09 DEBUG : sub/file2: Uploading chunk 2/1
2025/10/02 03:28:09 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub/file2" to batch
2025/10/02 03:28:10 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:10 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub/file2
2025/10/02 03:28:11 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub/file2
=== RUN   TestListJSON/Default
=== RUN   TestListJSON/FilesOnly
=== RUN   TestListJSON/DirsOnly
=== RUN   TestListJSON/Recurse
=== RUN   TestListJSON/SubDir
=== RUN   TestListJSON/NoModTime
=== RUN   TestListJSON/NoMimeType
=== RUN   TestListJSON/ShowHash
=== RUN   TestListJSON/HashTypes
2025/10/02 03:28:14 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
--- PASS: TestListJSON (11.46s)
    --- PASS: TestListJSON/Default (0.22s)
    --- PASS: TestListJSON/FilesOnly (0.22s)
    --- PASS: TestListJSON/DirsOnly (0.22s)
    --- PASS: TestListJSON/Recurse (0.48s)
    --- PASS: TestListJSON/SubDir (0.22s)
    --- PASS: TestListJSON/NoModTime (0.24s)
    --- PASS: TestListJSON/NoMimeType (0.23s)
    --- PASS: TestListJSON/ShowHash (0.26s)
    --- PASS: TestListJSON/HashTypes (0.21s)
    --- PASS: TestListJSON/Metadata (0.23s)
=== RUN   TestCount
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:28:17 DEBUG : potato2: Uploading chunk 1/1
2025/10/02 03:28:18 DEBUG : potato2: Uploading chunk 2/1
2025/10/02 03:28:18 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "potato2" to batch
2025/10/02 03:28:19 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:19 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: potato2
2025/10/02 03:28:20 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: potato2
2025/10/02 03:28:20 DEBUG : empty space: Uploading chunk 1/1
2025/10/02 03:28:20 DEBUG : empty space: Uploading chunk 2/1
2025/10/02 03:28:21 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "empty space" to batch
2025/10/02 03:28:21 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:21 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: empty space
2025/10/02 03:28:22 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: empty space
2025/10/02 03:28:22 DEBUG : sub dir/potato3: Uploading chunk 1/1
2025/10/02 03:28:23 DEBUG : sub dir/potato3: Uploading chunk 2/1
2025/10/02 03:28:23 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "sub dir/potato3" to batch
2025/10/02 03:28:23 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:23 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: sub dir/potato3
2025/10/02 03:28:24 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: sub dir/potato3
--- PASS: TestCount (13.67s)
=== RUN   TestCat
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:28:31 DEBUG : file1: Uploading chunk 1/1
2025/10/02 03:28:31 DEBUG : file1: Uploading chunk 2/1
2025/10/02 03:28:32 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "file1" to batch
2025/10/02 03:28:32 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:32 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: file1
2025/10/02 03:28:33 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: file1
2025/10/02 03:28:33 DEBUG : file2: Uploading chunk 1/1
2025/10/02 03:28:33 DEBUG : file2: Uploading chunk 2/1
2025/10/02 03:28:34 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "file2" to batch
2025/10/02 03:28:34 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:34 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: file2
2025/10/02 03:28:36 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: file2
--- PASS: TestCat (14.11s)
=== RUN   TestCopyURL
    run.go:180: Remote "Dropbox root 'rclone-test-wapisay1rote'", Local "Local file system at /tmp/rclone3363201560", Modify Window "1s"
2025/10/02 03:28:45 DEBUG : file1: Uploading chunk 1/1
2025/10/02 03:28:45 DEBUG : file1: Uploading chunk 2/1
2025/10/02 03:28:46 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "file1" to batch
2025/10/02 03:28:47 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:47 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: file1
2025/10/02 03:28:48 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: file1
2025/10/02 03:28:48 DEBUG : filename.txt: File name found in url
2025/10/02 03:28:48 DEBUG : filename.txt: Uploading chunk 1/1
2025/10/02 03:28:49 DEBUG : filename.txt: Uploading chunk 2/1
2025/10/02 03:28:49 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "filename.txt" to batch
2025/10/02 03:28:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: filename.txt
2025/10/02 03:28:50 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: filename.txt
2025/10/02 03:28:50 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2025/10/02 03:28:51 DEBUG : headerfilename.txt: Uploading chunk 1/1
2025/10/02 03:28:51 DEBUG : headerfilename.txt: Uploading chunk 2/1
2025/10/02 03:28:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "headerfilename.txt" to batch
2025/10/02 03:28:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:52 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: headerfilename.txt
2025/10/02 03:28:54 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: headerfilename.txt
2025/10/02 03:28:54 DEBUG : file2: Uploading chunk 1/1
2025/10/02 03:28:54 DEBUG : file2: Uploading chunk 2/1
2025/10/02 03:28:55 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Adding "file2" to batch
2025/10/02 03:28:55 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Batch idle for 500ms so committing
2025/10/02 03:28:55 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committing sync batch length 1 starting with: file2
2025/10/02 03:28:56 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Committed sync batch length 1 starting with: file2
--- PASS: TestCopyURL (14.34s)
PASS
2025/10/02 03:28:59 DEBUG : Dropbox root 'rclone-test-wapisay1rote': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCat|TestCopyURL|TestCount|TestDeduplicateNewestByHash|TestListDirSortedFn|TestListJSON)$'" - Finished OK in 1m39.880154598s (try 2/5)