"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestManualTransformFile|TestMove|TestTransformFile)$'" - Starting (try 5/5) 2026/01/23 01:54:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-qivofay5guxu" 2026/01/23 01:54:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/23 01:54:25 DEBUG : Starting OpenDrive session with ID: 587a00b1dd9d9edccfaddaa20b617ca9ba665c72c7c495ae68a53e246e2dc1d6 2026/01/23 01:54:26 DEBUG : Creating backend with remote "/tmp/rclone1608153414" === RUN TestConflicting run.go:185: Remote "OpenDrive root 'rclone-test-qivofay5guxu'", Local "Local file system at /tmp/rclone1608153414", Modify Window "1s" 2026/01/23 01:54:26 INFO : toe: Making directory 2026/01/23 01:54:27 INFO : toe/toe: Making directory 2026/01/23 01:54:27 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2026/01/23 01:54:27 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for checks to finish 2026/01/23 01:54:27 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for transfers to finish 2026/01/23 01:54:28 DEBUG : toe/toe/toe: Uploading chunk 0, size=11, remain=0 2026/01/23 01:54:29 DEBUG : toe/toe/toe: size = 11 OK 2026/01/23 01:54:29 DEBUG : toe/toe/toe: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/01/23 01:54:29 INFO : toe/toe/toe: Copied (new) 2026/01/23 01:54:29 DEBUG : Waiting for deletions to finish --- PASS: TestConflicting (5.13s) === RUN TestMove run.go:185: Remote "OpenDrive root 'rclone-test-qivofay5guxu'", Local "Local file system at /tmp/rclone1608153414", Modify Window "1s" 2026/01/23 01:54:31 DEBUG : empty_dir: Making directory with metadata 2026/01/23 01:54:31 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/23 01:54:31 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:31 INFO : tictacempty_dir: Making directory 2026/01/23 01:54:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:31 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:31 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:31 INFO : tictactoe: Making directory 2026/01/23 01:54:32 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:32 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:32 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:32 INFO : tictactoe/tictactoe: Making directory 2026/01/23 01:54:32 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:32 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/01/23 01:54:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:32 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/01/23 01:54:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:32 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for checks to finish 2026/01/23 01:54:32 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for transfers to finish 2026/01/23 01:54:33 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/01/23 01:54:34 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/01/23 01:54:34 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/01/23 01:54:34 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:34 INFO : toe/toe/toe.txt: Deleted 2026/01/23 01:54:34 INFO : toe/toe: Removing directory 2026/01/23 01:54:34 INFO : toe: Removing directory 2026/01/23 01:54:34 INFO : empty_dir: Removing directory 2026/01/23 01:54:34 DEBUG : Local file system at /tmp/rclone1608153414: deleted 3 directories --- PASS: TestMove (5.78s) === RUN TestTransformFile run.go:185: Remote "OpenDrive root 'rclone-test-qivofay5guxu'", Local "Local file system at /tmp/rclone1608153414", Modify Window "1s" 2026/01/23 01:54:37 DEBUG : empty_dir: Making directory with metadata 2026/01/23 01:54:37 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/23 01:54:37 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:37 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:37 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:37 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:37 INFO : tictacempty_dir: Making directory 2026/01/23 01:54:37 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:37 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:37 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:37 INFO : tictactoe: Making directory 2026/01/23 01:54:37 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:37 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:37 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:37 INFO : tictactoe/tictactoe: Making directory 2026/01/23 01:54:38 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:38 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/01/23 01:54:38 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:38 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/01/23 01:54:38 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:38 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:38 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for checks to finish 2026/01/23 01:54:38 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for transfers to finish 2026/01/23 01:54:39 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/01/23 01:54:40 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/01/23 01:54:40 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:40 INFO : toe/toe/toe.txt: Deleted 2026/01/23 01:54:40 INFO : toe/toe: Removing directory 2026/01/23 01:54:40 INFO : toe: Removing directory 2026/01/23 01:54:40 INFO : empty_dir: Removing directory 2026/01/23 01:54:40 DEBUG : Local file system at /tmp/rclone1608153414: deleted 3 directories 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:42 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestTransformFile (15.80s) === RUN TestManualTransformFile run.go:185: Remote "OpenDrive root 'rclone-test-qivofay5guxu'", Local "Local file system at /tmp/rclone1608153414", Modify Window "1s" 2026/01/23 01:54:52 DEBUG : Reset feature "Copy" 2026/01/23 01:54:52 DEBUG : Reset feature "Move" 2026/01/23 01:54:52 DEBUG : Reset feature "Copy" 2026/01/23 01:54:52 DEBUG : Reset feature "Move" 2026/01/23 01:54:52 DEBUG : empty_dir: Making directory with metadata 2026/01/23 01:54:52 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/23 01:54:52 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:52 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:52 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:53 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:53 INFO : tictacempty_dir: Making directory 2026/01/23 01:54:53 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/01/23 01:54:53 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:53 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:53 INFO : tictactoe: Making directory 2026/01/23 01:54:53 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:53 DEBUG : toe: transformed to: tictactoe 2026/01/23 01:54:53 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:53 INFO : tictactoe/tictactoe: Making directory 2026/01/23 01:54:54 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/01/23 01:54:54 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/01/23 01:54:54 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:54 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/01/23 01:54:54 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:54 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:54 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for checks to finish 2026/01/23 01:54:54 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for transfers to finish 2026/01/23 01:54:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/01/23 01:54:55 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/01/23 01:54:55 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/01/23 01:54:55 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/01/23 01:54:55 INFO : toe/toe/toe.txt: Deleted 2026/01/23 01:54:55 INFO : toe/toe: Removing directory 2026/01/23 01:54:55 INFO : toe: Removing directory 2026/01/23 01:54:55 INFO : empty_dir: Removing directory 2026/01/23 01:54:55 DEBUG : Local file system at /tmp/rclone1608153414: deleted 3 directories 2026/01/23 01:54:56 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/01/23 01:54:56 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/01/23 01:54:56 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:56 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:56 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/01/23 01:54:58 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2026/01/23 01:54:59 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/01/23 01:54:59 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2026/01/23 01:54:59 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2026/01/23 01:54:59 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted --- PASS: TestManualTransformFile (10.13s) === RUN TestBase64 run.go:185: Remote "OpenDrive root 'rclone-test-qivofay5guxu'", Local "Local file system at /tmp/rclone1608153414", Modify Window "1s" 2026/01/23 01:55:02 DEBUG : toe: transformed to: dG9l 2026/01/23 01:55:03 DEBUG : toe: transformed to: dG9l 2026/01/23 01:55:03 INFO : dG9l: Making directory 2026/01/23 01:55:03 DEBUG : toe: transformed to: dG9l 2026/01/23 01:55:03 DEBUG : toe: transformed to: dG9l 2026/01/23 01:55:03 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/01/23 01:55:03 INFO : dG9l/dG9l: Making directory 2026/01/23 01:55:03 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/01/23 01:55:03 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2026/01/23 01:55:03 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/01/23 01:55:03 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/01/23 01:55:03 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/01/23 01:55:03 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for checks to finish 2026/01/23 01:55:03 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Waiting for transfers to finish 2026/01/23 01:55:04 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2026/01/23 01:55:05 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2026/01/23 01:55:05 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/01/23 01:55:05 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2026/01/23 01:55:05 DEBUG : Waiting for deletions to finish 2026/01/23 01:55:06 DEBUG : dG9l: transformed to: toe 2026/01/23 01:55:06 DEBUG : dG9l: transformed to: toe 2026/01/23 01:55:06 DEBUG : toe: Directory modification time the same (differ by -28.436508ms, within tolerance 1s) 2026/01/23 01:55:06 DEBUG : Added delayed dir = "toe", newDst=toe 2026/01/23 01:55:06 DEBUG : dG9l: transformed to: toe 2026/01/23 01:55:06 DEBUG : dG9l/dG9l: transformed to: toe/toe 2026/01/23 01:55:06 INFO : toe/toe: Set directory modification time (using SetModTime) 2026/01/23 01:55:06 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2026/01/23 01:55:06 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2026/01/23 01:55:06 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/01/23 01:55:06 DEBUG : Local file system at /tmp/rclone1608153414: Waiting for checks to finish 2026/01/23 01:55:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2026/01/23 01:55:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2026/01/23 01:55:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2026/01/23 01:55:06 DEBUG : Local file system at /tmp/rclone1608153414: Waiting for transfers to finish 2026/01/23 01:55:06 DEBUG : Waiting for deletions to finish --- PASS: TestBase64 (5.93s) PASS 2026/01/23 01:55:08 DEBUG : OpenDrive root 'rclone-test-qivofay5guxu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestManualTransformFile|TestMove|TestTransformFile)$'" - Finished OK in 44.188365954s (try 5/5)