"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyOverSelf|TestCopyRedownload|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Starting (try 3/5) 2025/06/30 02:33:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yagixet1zoqo" 2025/06/30 02:33:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/30 02:33:14 DEBUG : Starting OpenDrive session with ID: 48862eac551b5fd96dfd2f5fa80bb9b2dc890b9094d86ee2e1ccc54a4d5c1e03 2025/06/30 02:33:15 DEBUG : Creating backend with remote "/tmp/rclone2808773761" === RUN TestMoveNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:15 INFO : sub dir2: Making directory 2025/06/30 02:33:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:15 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:33:15 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish 2025/06/30 02:33:16 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/30 02:33:17 INFO : sub dir/hello world: Copied (new) 2025/06/30 02:33:17 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (3.80s) === RUN TestSyncEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:19 DEBUG : sub dir2: Making directory with metadata 2025/06/30 02:33:19 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/30 02:33:19 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/30 02:33:19 INFO : sub dir2: Making directory 2025/06/30 02:33:19 INFO : sub dir: Making directory 2025/06/30 02:33:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:19 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:33:19 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish 2025/06/30 02:33:20 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:21 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/30 02:33:21 INFO : sub dir/hello world: Copied (new) 2025/06/30 02:33:21 DEBUG : Waiting for deletions to finish 2025/06/30 02:33:22 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (4.44s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:23 INFO : sub dir2: Making directory 2025/06/30 02:33:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:23 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:33:23 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish 2025/06/30 02:33:24 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:25 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/30 02:33:25 INFO : sub dir/hello world: Copied (new) 2025/06/30 02:33:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (3.54s) === RUN TestServerSideCopy run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:28 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-barocav1webo" 2025/06/30 02:33:29 DEBUG : Starting OpenDrive session with ID: 004b1035326ce27757e1b7b68ec597b3d900c44560014f32fcaef7ee382bf9d1 sync_test.go:591: Server side copy (if possible) OpenDrive root 'rclone-test-yagixet1zoqo' -> OpenDrive root 'rclone-test-barocav1webo' 2025/06/30 02:33:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:30 DEBUG : OpenDrive root 'rclone-test-barocav1webo': Waiting for checks to finish 2025/06/30 02:33:30 DEBUG : OpenDrive root 'rclone-test-barocav1webo': Waiting for transfers to finish 2025/06/30 02:33:31 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/30 02:33:31 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/30 02:33:31 DEBUG : OpenDrive root 'rclone-test-barocav1webo': Purge remote --- PASS: TestServerSideCopy (6.08s) === RUN TestCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:34 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:35 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/30 02:33:35 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:33:35 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish 2025/06/30 02:33:35 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/06/30 02:33:36 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/06/30 02:33:36 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (4.96s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:39 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-qusuqik3vuxu" 2025/06/30 02:33:40 DEBUG : Starting OpenDrive session with ID: 7a0ce17a79cc12c0f75c03f5180eac61736c481d93a3c8080f76fc6a3a1187f2 sync_test.go:627: Server side copy (if possible) OpenDrive root 'rclone-test-yagixet1zoqo' -> OpenDrive root 'rclone-test-qusuqik3vuxu' 2025/06/30 02:33:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:41 DEBUG : OpenDrive root 'rclone-test-qusuqik3vuxu': Waiting for checks to finish 2025/06/30 02:33:41 DEBUG : OpenDrive root 'rclone-test-qusuqik3vuxu': Waiting for transfers to finish 2025/06/30 02:33:42 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/30 02:33:42 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/30 02:33:43 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/06/30 02:33:44 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/30 02:33:44 DEBUG : OpenDrive root 'rclone-test-qusuqik3vuxu': Waiting for checks to finish 2025/06/30 02:33:44 DEBUG : OpenDrive root 'rclone-test-qusuqik3vuxu': Waiting for transfers to finish 2025/06/30 02:33:45 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/30 02:33:45 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/30 02:33:45 DEBUG : OpenDrive root 'rclone-test-qusuqik3vuxu': Purge remote --- PASS: TestServerSideCopyOverSelf (8.68s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:47 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:33:49 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/30 02:33:49 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:33:49 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish 2025/06/30 02:33:49 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/06/30 02:33:50 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/06/30 02:33:50 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/30 02:33:50 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (5.06s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:33:52 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 fstest.go:124: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:124 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:129 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:148 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:667 Error: Should be true Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: Modification time difference too big |-213910h28m46.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-06-30 02:33:53 +0000 UTC) (precision 1s) 2025/06/30 02:33:54 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-lumagor9nune" 2025/06/30 02:33:54 DEBUG : Starting OpenDrive session with ID: 8115595080920b97160a507baf14ac9a1746431b518cb8897891eef6e78ed7a3 sync_test.go:672: Server side copy (if possible) OpenDrive root 'rclone-test-yagixet1zoqo' -> OpenDrive root 'rclone-test-lumagor9nune' 2025/06/30 02:33:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:33:55 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for checks to finish 2025/06/30 02:33:55 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for transfers to finish 2025/06/30 02:33:56 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/30 02:33:56 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/30 02:33:56 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/06/30 02:33:58 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Using server-side directory move 2025/06/30 02:33:58 INFO : OpenDrive root 'rclone-test-lumagor9nune': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/30 02:33:58 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/30 02:33:58 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for checks to finish 2025/06/30 02:33:58 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for transfers to finish 2025/06/30 02:33:58 INFO : sub dir/hello world: Deleted 2025/06/30 02:33:58 INFO : sub dir/hello world: Moved (server-side) 2025/06/30 02:34:00 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/06/30 02:34:01 DEBUG : testing file moves 2025/06/30 02:34:02 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for checks to finish 2025/06/30 02:34:02 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/30 02:34:02 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Waiting for transfers to finish 2025/06/30 02:34:02 INFO : sub dir/hello world: Deleted 2025/06/30 02:34:02 INFO : sub dir/hello world: Moved (server-side) 2025/06/30 02:34:03 DEBUG : OpenDrive root 'rclone-test-lumagor9nune': Purge remote --- FAIL: TestServerSideMoveOverSelf (12.84s) === RUN TestCopyAfterDelete run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:34:05 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:34:06 ERROR : error listing: directory not found 2025/06/30 02:34:07 INFO : Local file system at /tmp/rclone2808773761: Making directory 2025/06/30 02:34:07 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for checks to finish 2025/06/30 02:34:07 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (4.05s) === RUN TestCopyRedownload run.go:180: Remote "OpenDrive root 'rclone-test-yagixet1zoqo'", Local "Local file system at /tmp/rclone2808773761", Modify Window "1s" 2025/06/30 02:34:09 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/30 02:34:11 DEBUG : Added delayed dir = "sub dir", newDst= 2025/06/30 02:34:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/30 02:34:11 DEBUG : Local file system at /tmp/rclone2808773761: Waiting for checks to finish 2025/06/30 02:34:11 DEBUG : Local file system at /tmp/rclone2808773761: Waiting for transfers to finish 2025/06/30 02:34:11 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/30 02:34:11 DEBUG : sub dir/hello world.87ffde68.partial: renamed to: sub dir/hello world 2025/06/30 02:34:11 INFO : sub dir/hello world: Copied (new) 2025/06/30 02:34:11 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (4.07s) FAIL 2025/06/30 02:34:12 DEBUG : OpenDrive root 'rclone-test-yagixet1zoqo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyOverSelf|TestCopyRedownload|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Finished ERROR in 58.808816727s (try 3/5): exit status 1: Failed [TestServerSideMoveOverSelf]