"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDirWithSuffix|TestSyncDoesntUpdateModtime)$'" - Starting (try 2/5)
2024/12/29 04:03:55 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zavetuf7ceja"
2024/12/29 04:03:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/29 04:03:55 DEBUG : Creating backend with remote "/tmp/rclone1530913280"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:03:56 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2024/12/29 04:03:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:03:57 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:03:57 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/29 04:03:57 DEBUG : sub dir/hello world: Uploading chunk 1/1
2024/12/29 04:03:57 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:03:57 DEBUG : sub dir/hello world: Uploading chunk 2/1
2024/12/29 04:03:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "sub dir/hello world" to batch
2024/12/29 04:03:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:03:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: sub dir/hello world
2024/12/29 04:03:59 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: sub dir/hello world
2024/12/29 04:04:00 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zusesew1piyu"
    sync_test.go:626: Server side copy (if possible) Dropbox root 'rclone-test-zavetuf7ceja' -> Dropbox root 'rclone-test-zusesew1piyu'
2024/12/29 04:04:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/29 04:04:01 DEBUG : Dropbox root 'rclone-test-zusesew1piyu': Waiting for checks to finish
2024/12/29 04:04:01 DEBUG : Dropbox root 'rclone-test-zusesew1piyu': Waiting for transfers to finish
2024/12/29 04:04:02 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2024/12/29 04:04:02 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:04:03 DEBUG : sub dir/hello world: Uploading chunk 1/1
2024/12/29 04:04:04 DEBUG : sub dir/hello world: Uploading chunk 2/1
2024/12/29 04:04:04 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "sub dir/hello world" to batch
2024/12/29 04:04:05 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:05 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: sub dir/hello world
2024/12/29 04:04:05 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: sub dir/hello world
2024/12/29 04:04:06 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/29 04:04:06 DEBUG : Dropbox root 'rclone-test-zusesew1piyu': Waiting for checks to finish
2024/12/29 04:04:06 DEBUG : Dropbox root 'rclone-test-zusesew1piyu': Waiting for transfers to finish
2024/12/29 04:04:07 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.kuyewom0" before starting
2024/12/29 04:04:09 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2024/12/29 04:04:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:04:09 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:04:09 DEBUG : sub dir/hello world.kuyewom0: server side copy: removing renamed existing file after operation
2024/12/29 04:04:10 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/29 04:04:10 DEBUG : sub dir/hello world: dropbox = a702a74d7a72e118c2934d408e62c092da7e1f49091a03a826955a22d34db7a3 OK
2024/12/29 04:04:10 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:04:10 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:04:10 DEBUG : Dropbox root 'rclone-test-zusesew1piyu': Purge remote
--- PASS: TestServerSideCopyOverSelf (19.80s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:04:16 DEBUG : foo: Uploading chunk 1/1
2024/12/29 04:04:16 DEBUG : foo: Uploading chunk 2/1
2024/12/29 04:04:16 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "foo" to batch
2024/12/29 04:04:17 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:17 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: foo
2024/12/29 04:04:17 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: foo
2024/12/29 04:04:18 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2024/12/29 04:04:18 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Waiting for checks to finish
2024/12/29 04:04:18 DEBUG : foo: dropbox = c7ade88fc7a21498a6a5e5c385e1f68bed822b72aa63c4a9a48a02c2466ee29e (Local file system at /tmp/rclone1530913280)
2024/12/29 04:04:18 DEBUG : foo: dropbox = 78ed58a609bc9948136b4f3ed3e6fb2cce68a32897280b693a0f97a941c3fd9e (Dropbox root 'rclone-test-zavetuf7ceja')
2024/12/29 04:04:18 DEBUG : foo: dropbox differ
2024/12/29 04:04:18 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Waiting for transfers to finish
2024/12/29 04:04:18 DEBUG : foo: Uploading chunk 1/1
2024/12/29 04:04:19 DEBUG : foo: Uploading chunk 2/1
2024/12/29 04:04:19 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "foo" to batch
2024/12/29 04:04:20 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:20 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: foo
2024/12/29 04:04:21 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: foo
2024/12/29 04:04:21 DEBUG : foo: dropbox = c7ade88fc7a21498a6a5e5c385e1f68bed822b72aa63c4a9a48a02c2466ee29e OK
2024/12/29 04:04:21 INFO  : foo: Copied (replaced existing)
2024/12/29 04:04:21 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
--- PASS: TestSyncDoesntUpdateModtime (8.25s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:04:23 DEBUG : Creating backend with remote "TestDropbox:rclone-test-senatol8fupu"
2024/12/29 04:04:25 DEBUG : potato2: Uploading chunk 1/1
2024/12/29 04:04:25 DEBUG : potato2: Uploading chunk 2/1
2024/12/29 04:04:26 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "potato2" to batch
2024/12/29 04:04:27 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:27 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: potato2
2024/12/29 04:04:28 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: potato2
2024/12/29 04:04:28 DEBUG : empty space: Uploading chunk 1/1
2024/12/29 04:04:29 DEBUG : empty space: Uploading chunk 2/1
2024/12/29 04:04:29 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "empty space" to batch
2024/12/29 04:04:30 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:30 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: empty space
2024/12/29 04:04:30 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: empty space
2024/12/29 04:04:31 DEBUG : potato3: Uploading chunk 1/1
2024/12/29 04:04:31 DEBUG : potato3: Uploading chunk 2/1
2024/12/29 04:04:32 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "potato3" to batch
2024/12/29 04:04:32 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:32 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: potato3
2024/12/29 04:04:33 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: potato3
2024/12/29 04:04:33 DEBUG : tomatoDir: Making directory
    sync_test.go:1705: Server side move (if possible) Dropbox root 'rclone-test-zavetuf7ceja' -> Dropbox root 'rclone-test-senatol8fupu'
2024/12/29 04:04:35 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2024/12/29 04:04:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:04:35 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:04:36 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/29 04:04:36 DEBUG : empty space: Uploading chunk 1/1
2024/12/29 04:04:36 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:04:36 DEBUG : empty space: Uploading chunk 2/1
2024/12/29 04:04:37 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Adding "empty space" to batch
2024/12/29 04:04:37 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Batch idle for 500ms so committing
2024/12/29 04:04:37 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Committing sync batch length 1 starting with: empty space
2024/12/29 04:04:38 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Committed sync batch length 1 starting with: empty space
2024/12/29 04:04:38 DEBUG : potato3: Uploading chunk 1/1
2024/12/29 04:04:39 DEBUG : potato3: Uploading chunk 2/1
2024/12/29 04:04:39 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Adding "potato3" to batch
2024/12/29 04:04:40 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Batch idle for 500ms so committing
2024/12/29 04:04:40 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Committing sync batch length 1 starting with: potato3
2024/12/29 04:04:41 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Committed sync batch length 1 starting with: potato3
2024/12/29 04:04:41 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Using server-side directory move
2024/12/29 04:04:42 INFO  : Dropbox root 'rclone-test-senatol8fupu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/29 04:04:43 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/29 04:04:43 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/12/29 04:04:43 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2024/12/29 04:04:43 DEBUG : empty space: Unchanged skipping
2024/12/29 04:04:43 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Waiting for checks to finish
2024/12/29 04:04:43 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2024/12/29 04:04:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:04:43 INFO  : empty space: Deleted
2024/12/29 04:04:43 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Waiting for transfers to finish
2024/12/29 04:04:43 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:04:43 INFO  : potato3: Deleted
2024/12/29 04:04:44 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2024/12/29 04:04:44 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2024/12/29 04:04:45 DEBUG : pacer: Reducing sleep to 22.5ms
2024/12/29 04:04:45 INFO  : potato2: Moved (server-side)
2024/12/29 04:04:45 DEBUG : pacer: Reducing sleep to 16.875ms
2024/12/29 04:04:45 INFO  : potato3: Moved (server-side)
2024/12/29 04:04:45 INFO  : tomatoDir: Removing directory
2024/12/29 04:04:46 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': deleted 1 directories
2024/12/29 04:04:48 DEBUG : pacer: Reducing sleep to 12.65625ms
2024/12/29 04:04:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-melezoq0xuqo"
2024/12/29 04:04:48 DEBUG : tomatoDir: Making directory
2024/12/29 04:04:48 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:04:49 DEBUG : Dropbox root 'rclone-test-melezoq0xuqo': Using server-side directory move
2024/12/29 04:04:50 INFO  : Dropbox root 'rclone-test-melezoq0xuqo': Server side directory move succeeded
2024/12/29 04:04:51 ERROR : : error listing: directory not found
2024/12/29 04:04:52 ERROR : : error listing: directory not found
2024/12/29 04:04:52 DEBUG : Dropbox root 'rclone-test-melezoq0xuqo': Purge remote
2024/12/29 04:04:53 DEBUG : Dropbox root 'rclone-test-senatol8fupu': Purge remote
2024/12/29 04:04:54 NOTICE: purge failed: path_lookup/not_found/
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (30.58s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Dropbox root 'rclone-test-zavetuf7ceja'", Local "Local file system at /tmp/rclone1530913280", Modify Window "1s"
2024/12/29 04:04:54 DEBUG : dst/one: Uploading chunk 1/1
2024/12/29 04:04:55 DEBUG : dst/one: Uploading chunk 2/1
2024/12/29 04:04:55 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "dst/one" to batch
2024/12/29 04:04:56 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:56 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: dst/one
2024/12/29 04:04:56 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: dst/one
2024/12/29 04:04:57 DEBUG : dst/two: Uploading chunk 1/1
2024/12/29 04:04:57 DEBUG : dst/two: Uploading chunk 2/1
2024/12/29 04:04:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "dst/two" to batch
2024/12/29 04:04:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:04:58 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: dst/two
2024/12/29 04:04:59 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: dst/two
2024/12/29 04:04:59 DEBUG : dst/three.txt: Uploading chunk 1/1
2024/12/29 04:05:00 DEBUG : dst/three.txt: Uploading chunk 2/1
2024/12/29 04:05:00 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "dst/three.txt" to batch
2024/12/29 04:05:01 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:05:01 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: dst/three.txt
2024/12/29 04:05:01 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: dst/three.txt
2024/12/29 04:05:02 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zavetuf7ceja/dst"
2024/12/29 04:05:02 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zavetuf7ceja/backup"
2024/12/29 04:05:03 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/29 04:05:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/29 04:05:03 DEBUG : two: Unchanged skipping
2024/12/29 04:05:03 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Waiting for checks to finish
2024/12/29 04:05:04 INFO  : one: Moved (server-side) to: one.bak
2024/12/29 04:05:04 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Waiting for transfers to finish
2024/12/29 04:05:05 DEBUG : one: Uploading chunk 1/1
2024/12/29 04:05:05 DEBUG : one: Uploading chunk 2/1
2024/12/29 04:05:06 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Adding "one" to batch
2024/12/29 04:05:06 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Batch idle for 500ms so committing
2024/12/29 04:05:06 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Committing sync batch length 1 starting with: one
2024/12/29 04:05:07 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Committed sync batch length 1 starting with: one
2024/12/29 04:05:07 DEBUG : one: dropbox = 525034bfc3bcd23ac5a856a953b1204f255bc2a5c313f3ab750d60ab581b8e81 OK
2024/12/29 04:05:07 INFO  : one: Copied (new)
2024/12/29 04:05:07 DEBUG : Waiting for deletions to finish
2024/12/29 04:05:08 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2024/12/29 04:05:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:05:09 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:05:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/29 04:05:09 INFO  : three.txt: Moved into backup dir
2024/12/29 04:05:10 DEBUG : dst/three.txt: Uploading chunk 1/1
2024/12/29 04:05:10 DEBUG : dst/three.txt: Uploading chunk 2/1
2024/12/29 04:05:11 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Adding "dst/three.txt" to batch
2024/12/29 04:05:11 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Batch idle for 500ms so committing
2024/12/29 04:05:11 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committing sync batch length 1 starting with: dst/three.txt
2024/12/29 04:05:12 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Committed sync batch length 1 starting with: dst/three.txt
2024/12/29 04:05:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/29 04:05:13 DEBUG : two: Unchanged skipping
2024/12/29 04:05:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/29 04:05:13 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Waiting for checks to finish
2024/12/29 04:05:13 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/29 04:05:14 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:05:14 INFO  : one.bak: Deleted
2024/12/29 04:05:14 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2024/12/29 04:05:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 04:05:15 DEBUG : pacer: Reducing sleep to 15ms
2024/12/29 04:05:15 INFO  : one: Moved (server-side) to: one.bak
2024/12/29 04:05:15 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Waiting for transfers to finish
2024/12/29 04:05:16 DEBUG : one: Uploading chunk 1/1
2024/12/29 04:05:16 DEBUG : one: Uploading chunk 2/1
2024/12/29 04:05:16 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Adding "one" to batch
2024/12/29 04:05:17 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Batch idle for 500ms so committing
2024/12/29 04:05:17 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Committing sync batch length 1 starting with: one
2024/12/29 04:05:18 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja/dst': Committed sync batch length 1 starting with: one
2024/12/29 04:05:18 DEBUG : one: dropbox = 1147280340b0797198e7d42f57f0340e2579e601846419ad967bdd7fa2dec259 OK
2024/12/29 04:05:18 INFO  : one: Copied (new)
2024/12/29 04:05:18 DEBUG : Waiting for deletions to finish
2024/12/29 04:05:18 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/29 04:05:18 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 04:05:18 INFO  : three.txt.bak: Deleted
2024/12/29 04:05:19 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/29 04:05:19 INFO  : three.txt: Moved into backup dir
2024/12/29 04:05:26 INFO  : Dropbox root 'rclone-test-zavetuf7ceja/backup': Committing uploads - please wait...
--- PASS: TestSyncBackupDirWithSuffix (31.83s)
PASS
2024/12/29 04:05:26 DEBUG : Dropbox root 'rclone-test-zavetuf7ceja': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDirWithSuffix|TestSyncDoesntUpdateModtime)$'" - Finished OK in 1m31.669771491s (try 2/5)