"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Starting (try 2/5) 2025/04/11 01:32:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-deqepeb5gamo" 2025/04/11 01:32:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/11 01:32:12 DEBUG : Starting OpenDrive session with ID: 923665a286b79938de49a6000c53ddd3fe34b8be851435bc1028f08850555644 2025/04/11 01:32:12 DEBUG : Creating backend with remote "/tmp/rclone2731571188" === RUN TestCopyEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:12 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/11 01:32:12 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/11 01:32:12 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/04/11 01:32:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/11 01:32:12 INFO : sub dir: Making directory 2025/04/11 01:32:13 INFO : sub dir2: Making directory 2025/04/11 01:32:13 INFO : sub dir2/sub sub dir2: Making directory 2025/04/11 01:32:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:13 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:13 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:14 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:15 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:15 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:16 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (6.03s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:18 INFO : sub dir2: Making directory 2025/04/11 01:32:18 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/11 01:32:18 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/11 01:32:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:18 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:18 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:19 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:20 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (3.44s) === RUN TestMoveEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:21 DEBUG : sub dir2: Making directory with metadata 2025/04/11 01:32:21 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/11 01:32:21 INFO : sub dir: Making directory 2025/04/11 01:32:22 INFO : sub dir2: Making directory 2025/04/11 01:32:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:22 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:22 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:23 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:24 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:24 INFO : sub dir/hello world: Copied (new) 2025/04/11 01:32:24 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:24 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/04/11 01:32:24 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (4.74s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:26 INFO : sub dir2: Making directory 2025/04/11 01:32:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:26 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:26 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:27 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:28 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:28 INFO : sub dir/hello world: Copied (new) 2025/04/11 01:32:28 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (3.63s) === RUN TestSyncEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:30 DEBUG : sub dir2: Making directory with metadata 2025/04/11 01:32:30 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/11 01:32:30 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/11 01:32:30 INFO : sub dir: Making directory 2025/04/11 01:32:30 INFO : sub dir2: Making directory 2025/04/11 01:32:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:31 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:31 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:31 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:32 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:32 INFO : sub dir/hello world: Copied (new) 2025/04/11 01:32:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:33 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (4.49s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:34 INFO : sub dir2: Making directory 2025/04/11 01:32:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:34 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:34 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:35 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:36 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:32:36 INFO : sub dir/hello world: Copied (new) 2025/04/11 01:32:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (3.46s) === RUN TestServerSideCopy run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:39 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-denoquq7fuli" 2025/04/11 01:32:40 DEBUG : Starting OpenDrive session with ID: be8e460a7d4ceb5949daafda8ca3c22f794c8ad297b7c63ff9ffda4037c582fa sync_test.go:590: Server side copy (if possible) OpenDrive root 'rclone-test-deqepeb5gamo' -> OpenDrive root 'rclone-test-denoquq7fuli' 2025/04/11 01:32:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:41 DEBUG : OpenDrive root 'rclone-test-denoquq7fuli': Waiting for checks to finish 2025/04/11 01:32:41 DEBUG : OpenDrive root 'rclone-test-denoquq7fuli': Waiting for transfers to finish 2025/04/11 01:32:42 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/11 01:32:42 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:42 DEBUG : OpenDrive root 'rclone-test-denoquq7fuli': Purge remote --- PASS: TestServerSideCopy (6.50s) === RUN TestCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:45 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/11 01:32:47 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:32:47 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:32:47 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/11 01:32:48 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/11 01:32:48 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" --- PASS: TestCopyOverSelf (5.56s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:51 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:32:52 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wetigiy7bifi" 2025/04/11 01:32:53 DEBUG : Starting OpenDrive session with ID: 2081f807c876d2e9ce82dc9a74b36275f770c839531ce3ce64a383e234668751 sync_test.go:626: Server side copy (if possible) OpenDrive root 'rclone-test-deqepeb5gamo' -> OpenDrive root 'rclone-test-wetigiy7bifi' 2025/04/11 01:32:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:32:53 DEBUG : OpenDrive root 'rclone-test-wetigiy7bifi': Waiting for checks to finish 2025/04/11 01:32:53 DEBUG : OpenDrive root 'rclone-test-wetigiy7bifi': Waiting for transfers to finish 2025/04/11 01:32:54 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/11 01:32:54 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:55 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/11 01:32:57 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/11 01:32:57 DEBUG : OpenDrive root 'rclone-test-wetigiy7bifi': Waiting for checks to finish 2025/04/11 01:32:57 DEBUG : OpenDrive root 'rclone-test-wetigiy7bifi': Waiting for transfers to finish 2025/04/11 01:32:57 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/11 01:32:57 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:32:57 DEBUG : OpenDrive root 'rclone-test-wetigiy7bifi': Purge remote --- PASS: TestServerSideCopyOverSelf (10.11s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:01 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:33:03 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:33:03 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/11 01:33:03 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish 2025/04/11 01:33:03 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/11 01:33:04 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/11 01:33:04 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/11 01:33:04 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" --- PASS: TestMoveOverSelf (5.85s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:07 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:33:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rojimel4yunu" 2025/04/11 01:33:09 DEBUG : Starting OpenDrive session with ID: a30b75ab93576c479174dc468e0a0d8bb45934983ae3029c47e56538a37e5564 sync_test.go:671: Server side copy (if possible) OpenDrive root 'rclone-test-deqepeb5gamo' -> OpenDrive root 'rclone-test-rojimel4yunu' 2025/04/11 01:33:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:33:09 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for checks to finish 2025/04/11 01:33:09 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for transfers to finish 2025/04/11 01:33:10 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/11 01:33:10 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:11 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/11 01:33:12 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Using server-side directory move 2025/04/11 01:33:12 INFO : OpenDrive root 'rclone-test-rojimel4yunu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/11 01:33:13 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/11 01:33:13 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for checks to finish 2025/04/11 01:33:13 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for transfers to finish 2025/04/11 01:33:13 INFO : sub dir/hello world: Deleted 2025/04/11 01:33:13 INFO : sub dir/hello world: Moved (server-side) 2025/04/11 01:33:15 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/04/11 01:33:16 DEBUG : testing file moves 2025/04/11 01:33:16 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for checks to finish 2025/04/11 01:33:16 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/04/11 01:33:16 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Waiting for transfers to finish 2025/04/11 01:33:16 INFO : sub dir/hello world: Deleted 2025/04/11 01:33:17 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:17 DEBUG : OpenDrive root 'rclone-test-rojimel4yunu': Purge remote --- PASS: TestServerSideMoveOverSelf (13.63s) === RUN TestCopyAfterDelete run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:21 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:33:22 ERROR : : error listing: directory not found 2025/04/11 01:33:22 INFO : Local file system at /tmp/rclone2731571188: Making directory 2025/04/11 01:33:22 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for checks to finish 2025/04/11 01:33:22 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" --- PASS: TestCopyAfterDelete (4.62s) === RUN TestCopyRedownload run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:25 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/11 01:33:27 DEBUG : Added delayed dir = "sub dir", newDst= 2025/04/11 01:33:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 01:33:27 DEBUG : Local file system at /tmp/rclone2731571188: Waiting for checks to finish 2025/04/11 01:33:27 DEBUG : Local file system at /tmp/rclone2731571188: Waiting for transfers to finish 2025/04/11 01:33:27 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 01:33:27 DEBUG : sub dir/hello world.87ffde68.partial: renamed to: sub dir/hello world 2025/04/11 01:33:27 INFO : sub dir/hello world: Copied (new) 2025/04/11 01:33:27 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" --- PASS: TestCopyRedownload (4.51s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:30 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/11 01:33:31 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/11 01:33:33 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/11 01:33:34 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-deqepeb5gamo/dst" 2025/04/11 01:33:35 DEBUG : Starting OpenDrive session with ID: f9000c5ae3f778c4c7c267da299bd289777ea8f661c15d24039afcfd4f6ea5ab 2025/04/11 01:33:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-deqepeb5gamo/backup" 2025/04/11 01:33:36 DEBUG : Starting OpenDrive session with ID: 2afff448d05019612e5876b965ada1f6ede8ca576cfcdb0620727d50f535dc50 2025/04/11 01:33:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/11 01:33:36 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for checks to finish 2025/04/11 01:33:36 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/11 01:33:36 DEBUG : two: Unchanged skipping 2025/04/11 01:33:37 INFO : one: Moved (server-side) to: one.bak 2025/04/11 01:33:37 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for transfers to finish 2025/04/11 01:33:38 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/11 01:33:39 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/11 01:33:39 INFO : one: Copied (new) 2025/04/11 01:33:39 DEBUG : Waiting for deletions to finish 2025/04/11 01:33:39 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 01:33:39 INFO : three.txt: Moved into backup dir 2025/04/11 01:33:41 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/11 01:33:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/11 01:33:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/11 01:33:43 DEBUG : two: Unchanged skipping 2025/04/11 01:33:43 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for checks to finish 2025/04/11 01:33:43 INFO : one.bak: Deleted 2025/04/11 01:33:44 INFO : one: Moved (server-side) to: one.bak 2025/04/11 01:33:44 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for transfers to finish 2025/04/11 01:33:45 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/11 01:33:45 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/11 01:33:45 INFO : one: Copied (new) 2025/04/11 01:33:45 DEBUG : Waiting for deletions to finish 2025/04/11 01:33:46 INFO : three.txt.bak: Deleted 2025/04/11 01:33:46 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 01:33:46 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.95s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-deqepeb5gamo'", Local "Local file system at /tmp/rclone2731571188", Modify Window "1s" 2025/04/11 01:33:49 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/11 01:33:51 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/11 01:33:53 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/11 01:33:54 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-deqepeb5gamo/dst" 2025/04/11 01:33:55 DEBUG : Starting OpenDrive session with ID: 29493d7e88e06ab079b4661197ba7c9b20da63793ac6c37edd48adf47803dd19 2025/04/11 01:33:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-deqepeb5gamo/backup" 2025/04/11 01:33:55 DEBUG : Starting OpenDrive session with ID: 5902f3d9ea5b801187e476bed168aba9f7869f2d040643e70519162fcc0daaa7 2025/04/11 01:33:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/11 01:33:56 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/11 01:33:56 DEBUG : two: Unchanged skipping 2025/04/11 01:33:56 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for checks to finish 2025/04/11 01:33:57 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/11 01:33:57 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for transfers to finish 2025/04/11 01:33:58 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/11 01:33:59 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/11 01:33:59 INFO : one: Copied (new) 2025/04/11 01:33:59 DEBUG : Waiting for deletions to finish 2025/04/11 01:33:59 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/11 01:33:59 INFO : three.txt: Moved into backup dir 2025/04/11 01:34:00 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/11 01:34:02 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/11 01:34:02 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for checks to finish 2025/04/11 01:34:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/11 01:34:02 DEBUG : two: Unchanged skipping 2025/04/11 01:34:02 INFO : one-2019-01-01: Deleted 2025/04/11 01:34:03 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/11 01:34:03 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo/dst': Waiting for transfers to finish 2025/04/11 01:34:03 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/11 01:34:04 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/11 01:34:04 INFO : one: Copied (new) 2025/04/11 01:34:04 DEBUG : Waiting for deletions to finish 2025/04/11 01:34:04 INFO : three-2019-01-01.txt: Deleted 2025/04/11 01:34:05 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/11 01:34:05 INFO : three.txt: Moved into backup dir fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestSyncBackupDirWithSuffixKeepExtension (26.23s) PASS 2025/04/11 01:34:15 DEBUG : OpenDrive root 'rclone-test-deqepeb5gamo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Finished OK in 2m4.121887629s (try 2/5)