"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Starting (try 3/5)
2026/01/29 01:53:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hezebip0wuvu"
2026/01/29 01:53:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/29 01:53:05 DEBUG : Starting OpenDrive session with ID: 06b1505bab1f1dee8d7a58434b71c7b4889f46e8f6d24f6feb8e0459c7be5456
2026/01/29 01:53:05 DEBUG : Creating backend with remote "/tmp/rclone3558008604"
=== RUN   TestServerSideCopyOverSelf
    run.go:185: Remote "OpenDrive root 'rclone-test-hezebip0wuvu'", Local "Local file system at /tmp/rclone3558008604", Modify Window "1s"
2026/01/29 01:53:07 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2026/01/29 01:53:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-sikokap6yela"
2026/01/29 01:53:10 DEBUG : Starting OpenDrive session with ID: ed235e101a4cdf66b1ad51a836340ce464a7d33bd2089492c843a92ea52ee9fe
    sync_test.go:656: Server side copy (if possible) OpenDrive root 'rclone-test-hezebip0wuvu' -> OpenDrive root 'rclone-test-sikokap6yela'
2026/01/29 01:53:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/29 01:53:11 DEBUG : OpenDrive root 'rclone-test-sikokap6yela': Waiting for checks to finish
2026/01/29 01:53:11 DEBUG : OpenDrive root 'rclone-test-sikokap6yela': Waiting for transfers to finish
2026/01/29 01:53:12 DEBUG : sub dir/hello world: size = 11 OK
2026/01/29 01:53:12 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/01/29 01:53:12 INFO  : sub dir/hello world: Copied (server-side copy)
2026/01/29 01:53:13 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0
2026/01/29 01:53:15 DEBUG : OpenDrive root 'rclone-test-sikokap6yela': Waiting for checks to finish
2026/01/29 01:53:15 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-hezebip0wuvu')
2026/01/29 01:53:15 DEBUG : sub dir/hello world: size = 11 (OpenDrive root 'rclone-test-sikokap6yela')
2026/01/29 01:53:15 DEBUG : sub dir/hello world: Sizes differ
2026/01/29 01:53:15 DEBUG : OpenDrive root 'rclone-test-sikokap6yela': Waiting for transfers to finish
2026/01/29 01:53:15 DEBUG : sub dir/hello world: size = 17 OK
2026/01/29 01:53:15 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/01/29 01:53:15 INFO  : sub dir/hello world: Copied (server-side copy)
2026/01/29 01:53:16 DEBUG : OpenDrive root 'rclone-test-sikokap6yela': Purge remote
--- PASS: TestServerSideCopyOverSelf (12.40s)
=== RUN   TestServerSideMoveOverSelf
    run.go:185: Remote "OpenDrive root 'rclone-test-hezebip0wuvu'", Local "Local file system at /tmp/rclone3558008604", Modify Window "1s"
2026/01/29 01:53:19 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2026/01/29 01:53:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hubupug2cozi"
2026/01/29 01:53:22 DEBUG : Starting OpenDrive session with ID: 72ca0c3f9123c6f73830f6951ecf4f62ff38614eecb99817131e2cf20f0d50e4
    sync_test.go:701: Server side copy (if possible) OpenDrive root 'rclone-test-hezebip0wuvu' -> OpenDrive root 'rclone-test-hubupug2cozi'
2026/01/29 01:53:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/29 01:53:22 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for checks to finish
2026/01/29 01:53:22 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for transfers to finish
2026/01/29 01:53:23 DEBUG : sub dir/hello world: size = 11 OK
2026/01/29 01:53:23 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/01/29 01:53:23 INFO  : sub dir/hello world: Copied (server-side copy)
2026/01/29 01:53:24 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0
2026/01/29 01:53:27 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Using server-side directory move
2026/01/29 01:53:27 INFO  : OpenDrive root 'rclone-test-hubupug2cozi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2026/01/29 01:53:27 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for checks to finish
2026/01/29 01:53:27 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-hezebip0wuvu')
2026/01/29 01:53:27 DEBUG : sub dir/hello world: size = 11 (OpenDrive root 'rclone-test-hubupug2cozi')
2026/01/29 01:53:27 DEBUG : sub dir/hello world: Sizes differ
2026/01/29 01:53:27 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for transfers to finish
2026/01/29 01:53:28 INFO  : sub dir/hello world: Deleted
2026/01/29 01:53:28 INFO  : sub dir/hello world: Moved (server-side)
2026/01/29 01:53:30 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0
2026/01/29 01:53:32 DEBUG : testing file moves
2026/01/29 01:53:32 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for checks to finish
2026/01/29 01:53:32 DEBUG : sub dir/hello world: size = 24 (OpenDrive root 'rclone-test-hezebip0wuvu')
2026/01/29 01:53:32 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-hubupug2cozi')
2026/01/29 01:53:32 DEBUG : sub dir/hello world: Sizes differ
2026/01/29 01:53:32 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Waiting for transfers to finish
2026/01/29 01:53:32 INFO  : sub dir/hello world: Deleted
2026/01/29 01:53:33 INFO  : sub dir/hello world: Moved (server-side)
Diff of "logger" and "lsf"
2d1
< 2011-12-30 12:59:59;c0e6b00e2b2068ef74d2ed582114e376;24;sub dir/hello world
    sync_test.go:3105: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:3105
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:727
        	Error:      	Received unexpected error:
        	            	logger does not match lsf! 
        	            	logger: 
        	            	
        	            	2011-12-30 12:59:59;c0e6b00e2b2068ef74d2ed582114e376;24;sub dir/hello world 
        	            	lsf: 
        	Test:       	TestServerSideMoveOverSelf
2026/01/29 01:53:33 DEBUG : OpenDrive root 'rclone-test-hubupug2cozi': Purge remote
--- FAIL: TestServerSideMoveOverSelf (17.40s)
=== RUN   TestBase64
    run.go:185: Remote "OpenDrive root 'rclone-test-hezebip0wuvu'", Local "Local file system at /tmp/rclone3558008604", Modify Window "1s"
2026/01/29 01:53:35 DEBUG : toe: transformed to: dG9l
2026/01/29 01:53:35 DEBUG : toe: transformed to: dG9l
2026/01/29 01:53:35 INFO  : dG9l: Making directory
2026/01/29 01:53:35 DEBUG : toe: transformed to: dG9l
2026/01/29 01:53:35 DEBUG : toe: transformed to: dG9l
2026/01/29 01:53:35 DEBUG : toe/toe: transformed to: dG9l/dG9l
2026/01/29 01:53:35 INFO  : dG9l/dG9l: Making directory
2026/01/29 01:53:36 DEBUG : toe/toe: transformed to: dG9l/dG9l
2026/01/29 01:53:36 DEBUG : toe.txt: transformed to: dG9lLnR4dA==
2026/01/29 01:53:36 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2026/01/29 01:53:36 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2026/01/29 01:53:36 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2026/01/29 01:53:36 DEBUG : OpenDrive root 'rclone-test-hezebip0wuvu': Waiting for checks to finish
2026/01/29 01:53:36 DEBUG : OpenDrive root 'rclone-test-hezebip0wuvu': Waiting for transfers to finish
2026/01/29 01:53:37 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0
2026/01/29 01:53:38 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK
2026/01/29 01:53:38 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2026/01/29 01:53:38 INFO  : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA==
2026/01/29 01:53:38 DEBUG : Waiting for deletions to finish
2026/01/29 01:53:39 DEBUG : dG9l: transformed to: toe
2026/01/29 01:53:39 DEBUG : dG9l: transformed to: toe
2026/01/29 01:53:39 DEBUG : toe: Directory modification time the same (differ by -601.621539ms, within tolerance 1s)
2026/01/29 01:53:39 DEBUG : Added delayed dir = "toe", newDst=toe
2026/01/29 01:53:40 DEBUG : dG9l: transformed to: toe
2026/01/29 01:53:40 DEBUG : dG9l/dG9l: transformed to: toe/toe
2026/01/29 01:53:40 INFO  : toe/toe: Set directory modification time (using SetModTime)
2026/01/29 01:53:40 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe
2026/01/29 01:53:40 DEBUG : dG9lLnR4dA==: transformed to: toe.txt
2026/01/29 01:53:40 DEBUG : toe/toe/toe.txt: size = 11 OK
2026/01/29 01:53:40 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s)
2026/01/29 01:53:40 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt
2026/01/29 01:53:40 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping
2026/01/29 01:53:40 DEBUG : Local file system at /tmp/rclone3558008604: Waiting for checks to finish
2026/01/29 01:53:40 DEBUG : Local file system at /tmp/rclone3558008604: Waiting for transfers to finish
2026/01/29 01:53:40 DEBUG : Waiting for deletions to finish
--- PASS: TestBase64 (9.24s)
FAIL
2026/01/29 01:53:44 DEBUG : OpenDrive root 'rclone-test-hezebip0wuvu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Finished ERROR in 41.512057256s (try 3/5): exit status 1: Failed [TestServerSideMoveOverSelf]