"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyEmptyDirectories|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDirWithSuffix|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5) 2025/04/05 04:47:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bijagaq3hano" 2025/04/05 04:47:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/05 04:47:43 DEBUG : Starting OpenDrive session with ID: 34d7002dea454cc99a03aedd42bea11f21e273e46aba513d74ecfed334facbfb 2025/04/05 04:47:43 DEBUG : Creating backend with remote "/tmp/rclone1385189180" === RUN TestCopyEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/05 04:47:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/05 04:47:43 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/04/05 04:47:43 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/05 04:47:44 INFO : sub dir: Making directory 2025/04/05 04:47:44 INFO : sub dir2: Making directory 2025/04/05 04:47:44 INFO : sub dir2/sub sub dir2: Making directory 2025/04/05 04:47:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:47:45 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:47:45 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:47:45 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:47:46 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:47:46 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:47 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (6.21s) === RUN TestMoveEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:49 DEBUG : sub dir2: Making directory with metadata 2025/04/05 04:47:49 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/05 04:47:49 INFO : sub dir: Making directory 2025/04/05 04:47:50 INFO : sub dir2: Making directory 2025/04/05 04:47:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:47:50 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:47:50 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:47:51 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:47:52 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:47:52 INFO : sub dir/hello world: Copied (new) 2025/04/05 04:47:52 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:52 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/04/05 04:47:52 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (4.92s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:54 INFO : sub dir2: Making directory 2025/04/05 04:47:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:47:54 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:47:54 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:47:55 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:47:56 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:47:56 INFO : sub dir/hello world: Copied (new) 2025/04/05 04:47:56 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (3.69s) === RUN TestSyncEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:47:58 DEBUG : sub dir2: Making directory with metadata 2025/04/05 04:47:58 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/05 04:47:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/05 04:47:58 INFO : sub dir: Making directory 2025/04/05 04:47:58 INFO : sub dir2: Making directory 2025/04/05 04:47:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:47:59 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:47:59 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:47:59 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:01 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:48:01 INFO : sub dir/hello world: Copied (new) 2025/04/05 04:48:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:01 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (5.46s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:03 INFO : sub dir2: Making directory 2025/04/05 04:48:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:48:04 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:48:04 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:48:05 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:06 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:48:06 INFO : sub dir/hello world: Copied (new) 2025/04/05 04:48:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (3.81s) === RUN TestServerSideCopy run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:08 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wahikiy8vico" 2025/04/05 04:48:10 DEBUG : Starting OpenDrive session with ID: 48b67e55edebe1eed3c2961a387518c7e53d4ebabf5a73b0b2b37a9862bdafad sync_test.go:590: Server side copy (if possible) OpenDrive root 'rclone-test-bijagaq3hano' -> OpenDrive root 'rclone-test-wahikiy8vico' 2025/04/05 04:48:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:48:11 DEBUG : OpenDrive root 'rclone-test-wahikiy8vico': Waiting for checks to finish 2025/04/05 04:48:11 DEBUG : OpenDrive root 'rclone-test-wahikiy8vico': Waiting for transfers to finish 2025/04/05 04:48:12 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/05 04:48:12 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:12 DEBUG : OpenDrive root 'rclone-test-wahikiy8vico': Purge remote --- PASS: TestServerSideCopy (7.03s) === RUN TestCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:15 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/05 04:48:17 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:48:17 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:48:17 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/05 04:48:19 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/05 04:48:19 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" --- PASS: TestCopyOverSelf (6.35s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:22 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vebilah8give" 2025/04/05 04:48:23 DEBUG : Starting OpenDrive session with ID: 31405b45e130bb2328467476fdf3f0a4e4add38344223dd934715c1f941af69f sync_test.go:626: Server side copy (if possible) OpenDrive root 'rclone-test-bijagaq3hano' -> OpenDrive root 'rclone-test-vebilah8give' 2025/04/05 04:48:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:48:24 DEBUG : OpenDrive root 'rclone-test-vebilah8give': Waiting for checks to finish 2025/04/05 04:48:24 DEBUG : OpenDrive root 'rclone-test-vebilah8give': Waiting for transfers to finish 2025/04/05 04:48:25 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/05 04:48:25 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:26 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/05 04:48:28 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/05 04:48:28 DEBUG : OpenDrive root 'rclone-test-vebilah8give': Waiting for checks to finish 2025/04/05 04:48:28 DEBUG : OpenDrive root 'rclone-test-vebilah8give': Waiting for transfers to finish 2025/04/05 04:48:28 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/05 04:48:28 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:28 DEBUG : OpenDrive root 'rclone-test-vebilah8give': Purge remote --- PASS: TestServerSideCopyOverSelf (10.02s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:32 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:33 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/05 04:48:33 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:48:33 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish 2025/04/05 04:48:34 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/05 04:48:34 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/05 04:48:34 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/05 04:48:34 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" --- PASS: TestMoveOverSelf (5.60s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:37 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:48:39 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wigozak3zeja" 2025/04/05 04:48:39 DEBUG : Starting OpenDrive session with ID: 9239404660d3cb47014871d3067fa21f7b7fdda491c0465833a9f8d60892f7f5 sync_test.go:671: Server side copy (if possible) OpenDrive root 'rclone-test-bijagaq3hano' -> OpenDrive root 'rclone-test-wigozak3zeja' 2025/04/05 04:48:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:48:40 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for checks to finish 2025/04/05 04:48:40 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for transfers to finish 2025/04/05 04:48:41 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/04/05 04:48:41 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:42 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/05 04:48:43 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Using server-side directory move 2025/04/05 04:48:43 INFO : OpenDrive root 'rclone-test-wigozak3zeja': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/05 04:48:44 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for checks to finish 2025/04/05 04:48:44 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/05 04:48:44 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for transfers to finish 2025/04/05 04:48:44 INFO : sub dir/hello world: Deleted 2025/04/05 04:48:44 INFO : sub dir/hello world: Moved (server-side) 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 2025/04/05 04:48:53 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/04/05 04:48:54 DEBUG : testing file moves 2025/04/05 04:48:55 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/04/05 04:48:55 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for checks to finish 2025/04/05 04:48:55 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Waiting for transfers to finish 2025/04/05 04:48:55 INFO : sub dir/hello world: Deleted 2025/04/05 04:48:55 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:56 DEBUG : OpenDrive root 'rclone-test-wigozak3zeja': Purge remote --- PASS: TestServerSideMoveOverSelf (22.11s) === RUN TestCopyAfterDelete run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:48:59 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:49:01 ERROR : : error listing: directory not found 2025/04/05 04:49:01 INFO : Local file system at /tmp/rclone1385189180: Making directory 2025/04/05 04:49:01 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for checks to finish 2025/04/05 04:49:01 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" --- PASS: TestCopyAfterDelete (4.74s) === RUN TestCopyRedownload run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:49:04 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/05 04:49:06 DEBUG : Added delayed dir = "sub dir", newDst= 2025/04/05 04:49:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/05 04:49:06 DEBUG : Local file system at /tmp/rclone1385189180: Waiting for checks to finish 2025/04/05 04:49:06 DEBUG : Local file system at /tmp/rclone1385189180: Waiting for transfers to finish 2025/04/05 04:49:06 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/05 04:49:06 DEBUG : sub dir/hello world.87ffde68.partial: renamed to: sub dir/hello world 2025/04/05 04:49:06 INFO : sub dir/hello world: Copied (new) 2025/04/05 04:49:06 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" --- PASS: TestCopyRedownload (4.70s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:49:09 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/05 04:49:11 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/05 04:49:12 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/05 04:49:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bijagaq3hano/dst" 2025/04/05 04:49:14 DEBUG : Starting OpenDrive session with ID: de94f8b767d4ae8269d7375a8f9b5f7bc7a61f53e79d4d268b19683b22c9a5d4 2025/04/05 04:49:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bijagaq3hano/backup" 2025/04/05 04:49:15 DEBUG : Starting OpenDrive session with ID: a40c7802900bc8011b1060d14a5d9ea33a1020ff56d48615606a0eb2d5ff49ac 2025/04/05 04:49:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/05 04:49:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/05 04:49:16 DEBUG : two: Unchanged skipping 2025/04/05 04:49:16 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano/dst': Waiting for checks to finish 2025/04/05 04:49:16 INFO : one: Moved (server-side) to: one.bak 2025/04/05 04:49:16 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano/dst': Waiting for transfers to finish 2025/04/05 04:49:17 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/05 04:49:18 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/05 04:49:18 INFO : one: Copied (new) 2025/04/05 04:49:18 DEBUG : Waiting for deletions to finish 2025/04/05 04:49:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/05 04:49:19 INFO : three.txt: Moved into backup dir 2025/04/05 04:49:20 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/05 04:49:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/05 04:49:21 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano/dst': Waiting for checks to finish 2025/04/05 04:49:21 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/05 04:49:21 DEBUG : two: Unchanged skipping 2025/04/05 04:49:22 INFO : one.bak: Deleted 2025/04/05 04:49:22 INFO : one: Moved (server-side) to: one.bak 2025/04/05 04:49:22 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano/dst': Waiting for transfers to finish 2025/04/05 04:49:23 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/05 04:49:24 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/05 04:49:24 INFO : one: Copied (new) 2025/04/05 04:49:24 DEBUG : Waiting for deletions to finish 2025/04/05 04:49:24 INFO : three.txt.bak: Deleted 2025/04/05 04:49:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/05 04:49:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.15s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:49:28 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/05 04:49:30 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/05 04:49:31 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/05 04:49:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bijagaq3hano/dst" 2025/04/05 04:49:33 DEBUG : Config file has changed externally - reloading 2025/04/05 04:49:33 DEBUG : Starting OpenDrive session with ID: 3e6390e60fcd177ad2f3187718921885cbe814ab0e01df38c470f7ec8493b9f4 2025/04/05 04:49:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/05 04:49:34 DEBUG : one: same parent dir (ODFfODIwNDIzM19kSU9CZQ) - using file/rename instead of move_copy for one-2019-01-01 2025/04/05 04:49:34 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/05 04:49:35 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/05 04:49:36 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/05 04:49:36 INFO : one: Copied (new) 2025/04/05 04:49:36 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/05 04:49:36 DEBUG : two: Unchanged skipping 2025/04/05 04:49:36 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/05 04:49:37 DEBUG : three.txt: same parent dir (ODFfODIwNDIzM19kSU9CZQ) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/04/05 04:49:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/05 04:49:37 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/04/05 04:49:38 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/04/05 04:49:38 INFO : three.txt: Copied (new) 2025/04/05 04:49:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/05 04:49:40 INFO : one-2019-01-01: Deleted 2025/04/05 04:49:40 DEBUG : one: same parent dir (ODFfODIwNDIzM19kSU9CZQ) - using file/rename instead of move_copy for one-2019-01-01 2025/04/05 04:49:40 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/05 04:49:41 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/05 04:49:42 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/05 04:49:42 INFO : one: Copied (new) 2025/04/05 04:49:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/05 04:49:42 DEBUG : two: Unchanged skipping 2025/04/05 04:49:42 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/05 04:49:42 INFO : three-2019-01-01.txt: Deleted 2025/04/05 04:49:42 DEBUG : three.txt: same parent dir (ODFfODIwNDIzM19kSU9CZQ) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/04/05 04:49:43 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/05 04:49:43 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/04/05 04:49:45 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/04/05 04:49:45 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (19.67s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" 2025/04/05 04:49:47 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/05 04:49:47 INFO : sub dir: Making directory 2025/04/05 04:49:47 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-bijagaq3hano'", Local "Local file system at /tmp/rclone1385189180", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (16.44s) PASS 2025/04/05 04:50:03 DEBUG : OpenDrive root 'rclone-test-bijagaq3hano': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyEmptyDirectories|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDirWithSuffix|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncSuffixKeepExtension)$'" - Finished OK in 2m21.320110206s (try 2/5)