"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncWithExclude|TestSyncWithUpdateOlder)$'" - Starting (try 2/5)
2024/10/04 02:21:39 DEBUG : Creating backend with remote "TestDropbox:rclone-test-puzotej4sudo"
2024/10/04 02:21:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/04 02:21:39 DEBUG : Creating backend with remote "/tmp/rclone1312145536"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
2024/10/04 02:21:40 DEBUG : sub dir/hello world: Uploading chunk 1/1
2024/10/04 02:21:40 DEBUG : sub dir/hello world: Uploading chunk 2/1
2024/10/04 02:21:41 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "sub dir/hello world" to batch
2024/10/04 02:21:41 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:21:41 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: sub dir/hello world
2024/10/04 02:21:42 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: sub dir/hello world
2024/10/04 02:21:43 DEBUG : Creating backend with remote "TestDropbox:rclone-test-vonovaz9vujo"
    sync_test.go:626: Server side copy (if possible) Dropbox root 'rclone-test-puzotej4sudo' -> Dropbox root 'rclone-test-vonovaz9vujo'
2024/10/04 02:21:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/10/04 02:21:44 DEBUG : Dropbox root 'rclone-test-vonovaz9vujo': Waiting for checks to finish
2024/10/04 02:21:44 DEBUG : Dropbox root 'rclone-test-vonovaz9vujo': Waiting for transfers to finish
2024/10/04 02:21:45 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2024/10/04 02:21:45 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
2024/10/04 02:21:45 DEBUG : sub dir/hello world: Uploading chunk 1/1
2024/10/04 02:21:46 DEBUG : sub dir/hello world: Uploading chunk 2/1
2024/10/04 02:21:46 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "sub dir/hello world" to batch
2024/10/04 02:21:47 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:21:47 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: sub dir/hello world
2024/10/04 02:21:48 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: sub dir/hello world
2024/10/04 02:21:49 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/10/04 02:21:49 DEBUG : Dropbox root 'rclone-test-vonovaz9vujo': Waiting for checks to finish
2024/10/04 02:21:49 DEBUG : Dropbox root 'rclone-test-vonovaz9vujo': Waiting for transfers to finish
2024/10/04 02:21:49 ERROR : sub dir/hello world: Failed to copy: copy failed: to/conflict/file/
    sync_test.go:639: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639
        	Error:      	Received unexpected error:
        	            	copy failed: to/conflict/file/
        	Test:       	TestServerSideCopyOverSelf
2024/10/04 02:21:49 DEBUG : Dropbox root 'rclone-test-vonovaz9vujo': Purge remote
--- FAIL: TestServerSideCopyOverSelf (14.10s)
=== RUN   TestSyncWithExclude
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
2024/10/04 02:21:53 DEBUG : potato2: Uploading chunk 1/1
2024/10/04 02:21:54 DEBUG : potato2: Uploading chunk 2/1
2024/10/04 02:21:54 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "potato2" to batch
2024/10/04 02:21:55 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:21:55 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: potato2
2024/10/04 02:21:55 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: potato2
2024/10/04 02:21:56 DEBUG : empty space: Uploading chunk 1/1
2024/10/04 02:21:56 DEBUG : empty space: Uploading chunk 2/1
2024/10/04 02:21:57 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "empty space" to batch
2024/10/04 02:21:57 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:21:57 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: empty space
2024/10/04 02:21:58 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: empty space
2024/10/04 02:21:58 DEBUG : enormous: Excluded (Size Filter)
2024/10/04 02:21:58 DEBUG : enormous: Excluded
2024/10/04 02:21:58 DEBUG : potato2: Excluded (Size Filter)
2024/10/04 02:21:58 DEBUG : potato2: Excluded
2024/10/04 02:21:58 DEBUG : potato2: Excluded (Size Filter)
2024/10/04 02:21:58 DEBUG : potato2: Excluded
2024/10/04 02:21:58 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/10/04 02:21:58 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for checks to finish
2024/10/04 02:21:58 DEBUG : empty space: Unchanged skipping
2024/10/04 02:21:58 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for transfers to finish
2024/10/04 02:21:58 DEBUG : Waiting for deletions to finish
2024/10/04 02:21:58 INFO  : There was nothing to transfer
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
2024/10/04 02:21:58 DEBUG : enormous: Excluded (Size Filter)
2024/10/04 02:21:58 DEBUG : enormous: Excluded
2024/10/04 02:21:58 DEBUG : potato2: Excluded (Size Filter)
2024/10/04 02:21:58 DEBUG : potato2: Excluded
2024/10/04 02:21:59 DEBUG : potato2: Excluded (Size Filter)
2024/10/04 02:21:59 DEBUG : potato2: Excluded
2024/10/04 02:21:59 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s)
2024/10/04 02:21:59 DEBUG : empty space: Unchanged skipping
2024/10/04 02:21:59 DEBUG : Local file system at /tmp/rclone1312145536: Waiting for checks to finish
2024/10/04 02:21:59 DEBUG : Local file system at /tmp/rclone1312145536: Waiting for transfers to finish
2024/10/04 02:21:59 DEBUG : Waiting for deletions to finish
2024/10/04 02:21:59 INFO  : There was nothing to transfer
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
--- PASS: TestSyncWithExclude (8.31s)
=== RUN   TestSyncWithUpdateOlder
    run.go:180: Remote "Dropbox root 'rclone-test-puzotej4sudo'", Local "Local file system at /tmp/rclone1312145536", Modify Window "1s"
2024/10/04 02:22:02 DEBUG : one: Uploading chunk 1/1
2024/10/04 02:22:02 DEBUG : one: Uploading chunk 2/1
2024/10/04 02:22:03 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "one" to batch
2024/10/04 02:22:03 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:03 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: one
2024/10/04 02:22:04 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: one
2024/10/04 02:22:04 DEBUG : two: Uploading chunk 1/1
2024/10/04 02:22:04 DEBUG : two: Uploading chunk 2/1
2024/10/04 02:22:05 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "two" to batch
2024/10/04 02:22:05 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:05 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: two
2024/10/04 02:22:07 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: two
2024/10/04 02:22:07 DEBUG : three: Uploading chunk 1/1
2024/10/04 02:22:07 DEBUG : three: Uploading chunk 2/1
2024/10/04 02:22:08 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "three" to batch
2024/10/04 02:22:08 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:08 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: three
2024/10/04 02:22:09 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: three
2024/10/04 02:22:10 DEBUG : four: Uploading chunk 1/1
2024/10/04 02:22:10 DEBUG : four: Uploading chunk 2/1
2024/10/04 02:22:11 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "four" to batch
2024/10/04 02:22:11 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:11 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: four
2024/10/04 02:22:12 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: four
2024/10/04 02:22:12 DEBUG : five: Need to transfer - File not found at Destination
2024/10/04 02:22:12 DEBUG : four: Sizes differ (src 4 vs dst 8)
2024/10/04 02:22:12 DEBUG : one: Destination is newer than source, skipping
2024/10/04 02:22:12 DEBUG : three: Sizes identical
2024/10/04 02:22:12 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 (Local file system at /tmp/rclone1312145536)
2024/10/04 02:22:12 DEBUG : two: dropbox = 00b165e375c521d9e15870a04a889919b652d5f0b499d7e4f9304fb142dc36d8 (Dropbox root 'rclone-test-puzotej4sudo')
2024/10/04 02:22:12 DEBUG : two: dropbox differ
2024/10/04 02:22:12 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for checks to finish
2024/10/04 02:22:12 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2024/10/04 02:22:12 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2024/10/04 02:22:12 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for transfers to finish
2024/10/04 02:22:13 DEBUG : four: Uploading chunk 1/1
2024/10/04 02:22:13 DEBUG : five: Uploading chunk 1/1
2024/10/04 02:22:13 DEBUG : two: Uploading chunk 1/1
2024/10/04 02:22:13 DEBUG : five: Uploading chunk 2/1
2024/10/04 02:22:13 DEBUG : four: Uploading chunk 2/1
2024/10/04 02:22:13 DEBUG : two: Uploading chunk 2/1
2024/10/04 02:22:13 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "five" to batch
2024/10/04 02:22:13 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "two" to batch
2024/10/04 02:22:14 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "four" to batch
2024/10/04 02:22:14 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:14 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 3 starting with: five
2024/10/04 02:22:15 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 3 starting with: five
2024/10/04 02:22:15 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2024/10/04 02:22:15 INFO  : two: Copied (replaced existing)
2024/10/04 02:22:15 DEBUG : five: dropbox = 785ec2cf1199779cb1593eac85d7465db3603834e9edd6d1420f2b369bb01dc3 OK
2024/10/04 02:22:15 INFO  : five: Copied (new)
2024/10/04 02:22:15 DEBUG : four: dropbox = ad1d7d5339655cf0a7e22a8560b56f563ea95eba7b0a043776c99a4db108702a OK
2024/10/04 02:22:15 INFO  : four: Copied (replaced existing)
2024/10/04 02:22:15 DEBUG : Waiting for deletions to finish
2024/10/04 02:22:16 DEBUG : one: Destination is newer than source, skipping
2024/10/04 02:22:16 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for checks to finish
2024/10/04 02:22:16 DEBUG : three: dropbox = 1dbfa84faa926d00a27e7578746a13ad3063e0e9bde3b25015e545ad80f5f703 (Local file system at /tmp/rclone1312145536)
2024/10/04 02:22:16 DEBUG : three: dropbox = 8c4bfd5e4dc14d49026d0a5aa224be92b3af4b622e8c08296defc8e299b0b6fa (Dropbox root 'rclone-test-puzotej4sudo')
2024/10/04 02:22:16 DEBUG : three: dropbox differ
2024/10/04 02:22:16 DEBUG : five: dropbox = 785ec2cf1199779cb1593eac85d7465db3603834e9edd6d1420f2b369bb01dc3 OK
2024/10/04 02:22:16 DEBUG : five: Size and dropbox of src and dst objects identical
2024/10/04 02:22:16 DEBUG : four: dropbox = ad1d7d5339655cf0a7e22a8560b56f563ea95eba7b0a043776c99a4db108702a OK
2024/10/04 02:22:16 DEBUG : four: Size and dropbox of src and dst objects identical
2024/10/04 02:22:16 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2024/10/04 02:22:16 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2024/10/04 02:22:16 DEBUG : two: Size and dropbox of src and dst objects identical
2024/10/04 02:22:16 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2024/10/04 02:22:16 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2024/10/04 02:22:16 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2024/10/04 02:22:16 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Waiting for transfers to finish
2024/10/04 02:22:16 DEBUG : three: Uploading chunk 1/1
2024/10/04 02:22:16 DEBUG : three: Uploading chunk 2/1
2024/10/04 02:22:17 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Adding "three" to batch
2024/10/04 02:22:17 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Batch idle for 500ms so committing
2024/10/04 02:22:17 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committing sync batch length 1 starting with: three
2024/10/04 02:22:18 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Committed sync batch length 1 starting with: three
2024/10/04 02:22:18 DEBUG : three: dropbox = 1dbfa84faa926d00a27e7578746a13ad3063e0e9bde3b25015e545ad80f5f703 OK
2024/10/04 02:22:18 INFO  : three: Copied (replaced existing)
2024/10/04 02:22:18 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (20.80s)
FAIL
2024/10/04 02:22:22 DEBUG : Dropbox root 'rclone-test-puzotej4sudo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncWithExclude|TestSyncWithUpdateOlder)$'" - Finished ERROR in 44.169891205s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]