"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCopyEmptyDirectories|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Starting (try 2/5)
2025/11/05 14:29:33 DEBUG : Creating backend with remote "TestDropbox:rclone-test-qonoger4dado"
2025/11/05 14:29:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/05 14:29:33 DEBUG : Creating backend with remote "/tmp/rclone1239291294"
=== RUN   TestCopyEmptyDirectories
    run.go:185: Remote "Dropbox root 'rclone-test-qonoger4dado'", Local "Local file system at /tmp/rclone1239291294", Modify Window "1s"
2025/11/05 14:29:33 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/11/05 14:29:33 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/11/05 14:29:33 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/11/05 14:29:34 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/05 14:29:34 INFO  : sub dir2: Making directory
2025/11/05 14:29:35 INFO  : sub dir: Making directory
2025/11/05 14:29:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/11/05 14:29:36 INFO  : sub dir2/sub sub dir2: Making directory
2025/11/05 14:29:36 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/11/05 14:29:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/11/05 14:29:37 DEBUG : pacer: Reducing sleep to 15ms
2025/11/05 14:29:37 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/11/05 14:29:37 DEBUG : pacer: Reducing sleep to 11.25ms
2025/11/05 14:29:37 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for checks to finish
2025/11/05 14:29:37 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for transfers to finish
2025/11/05 14:29:37 DEBUG : pacer: Reducing sleep to 10ms
2025/11/05 14:29:37 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/11/05 14:29:38 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Adding "sub dir/hello world" to batch
2025/11/05 14:29:38 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Batch idle for 500ms so committing
2025/11/05 14:29:38 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committing sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:39 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committed sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:39 DEBUG : sub dir/hello world: size = 11 OK
2025/11/05 14:29:39 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/11/05 14:29:39 INFO  : sub dir/hello world: Copied (new)
2025/11/05 14:29:39 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (10.99s)
=== RUN   TestSyncEmptyDirectories
    run.go:185: Remote "Dropbox root 'rclone-test-qonoger4dado'", Local "Local file system at /tmp/rclone1239291294", Modify Window "1s"
2025/11/05 14:29:44 DEBUG : sub dir2: Making directory with metadata
2025/11/05 14:29:44 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/11/05 14:29:44 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/05 14:29:44 INFO  : sub dir2: Making directory
2025/11/05 14:29:45 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/11/05 14:29:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/11/05 14:29:45 DEBUG : pacer: Reducing sleep to 15ms
2025/11/05 14:29:45 INFO  : sub dir: Making directory
2025/11/05 14:29:46 DEBUG : pacer: Reducing sleep to 11.25ms
2025/11/05 14:29:46 DEBUG : pacer: Reducing sleep to 10ms
2025/11/05 14:29:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/11/05 14:29:46 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for checks to finish
2025/11/05 14:29:46 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for transfers to finish
2025/11/05 14:29:46 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/11/05 14:29:47 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/11/05 14:29:47 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Adding "sub dir/hello world" to batch
2025/11/05 14:29:47 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Batch idle for 500ms so committing
2025/11/05 14:29:47 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committing sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:48 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committed sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:48 DEBUG : sub dir/hello world: size = 11 OK
2025/11/05 14:29:48 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/11/05 14:29:48 INFO  : sub dir/hello world: Copied (new)
2025/11/05 14:29:48 DEBUG : Waiting for deletions to finish
2025/11/05 14:29:49 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (8.36s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:185: Remote "Dropbox root 'rclone-test-qonoger4dado'", Local "Local file system at /tmp/rclone1239291294", Modify Window "1s"
2025/11/05 14:29:52 INFO  : sub dir2: Making directory
2025/11/05 14:29:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/11/05 14:29:53 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for checks to finish
2025/11/05 14:29:53 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Waiting for transfers to finish
2025/11/05 14:29:53 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/11/05 14:29:53 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/11/05 14:29:54 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Adding "sub dir/hello world" to batch
2025/11/05 14:29:54 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Batch idle for 500ms so committing
2025/11/05 14:29:54 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committing sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:55 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Committed sync batch length 1 starting with: sub dir/hello world
2025/11/05 14:29:55 DEBUG : sub dir/hello world: size = 11 OK
2025/11/05 14:29:55 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/11/05 14:29:55 INFO  : sub dir/hello world: Copied (new)
2025/11/05 14:29:55 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (5.72s)
PASS
2025/11/05 14:29:58 DEBUG : Dropbox root 'rclone-test-qonoger4dado': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCopyEmptyDirectories|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Finished OK in 26.062253726s (try 2/5)