"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestFixCase|TestManualTransformFile|TestMove|TestTransformFile)$'" - Starting (try 3/5) 2025/06/17 02:23:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nepomov8duge" 2025/06/17 02:23:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/17 02:23:14 DEBUG : Starting OpenDrive session with ID: f766d2ecdd44dd6b30f1d9a5467c704b6a0efda72e751261dde2ce6fa168f1bf 2025/06/17 02:23:15 DEBUG : Creating backend with remote "/tmp/rclone1191831557" === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:23:16 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2025/06/17 02:23:18 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 run.go:285: Failed to put "SUBDIRA/subdirb/SUBDIRC/HELLO" to "OpenDrive root 'rclone-test-nepomov8duge'": failed to get folder list: Directory doesn't exist (Error 404) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:298: Flushing the directory cache fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2649 Error: Not equal: expected: []string{} actual : []string{"SUBDIRA"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "SUBDIRA" } Test: TestFixCase Messages: directories --- FAIL: TestFixCase (13.14s) === RUN TestConflicting run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:23:28 INFO : toe: Making directory 2025/06/17 02:23:29 INFO : toe/toe: Making directory 2025/06/17 02:23:29 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2025/06/17 02:23:29 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for checks to finish 2025/06/17 02:23:29 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for transfers to finish 2025/06/17 02:23:30 DEBUG : toe/toe/toe: Uploading chunk 0, size=11, remain=0 2025/06/17 02:23:31 DEBUG : toe/toe/toe: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/17 02:23:31 INFO : toe/toe/toe: Copied (new) 2025/06/17 02:23:31 DEBUG : Waiting for deletions to finish 2025/06/17 02:23:31 INFO : SUBDIRA: Removing directory 2025/06/17 02:23:31 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': deleted 1 directories run.go:125: removing dir "toe/toe" failed - try 1/3: folder not empty run.go:125: removing dir "toe/toe" failed - try 2/3: folder not empty --- PASS: TestConflicting (7.61s) === RUN TestMove run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:23:36 DEBUG : empty_dir: Making directory with metadata 2025/06/17 02:23:36 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/17 02:23:36 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:36 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:36 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:36 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:36 INFO : tictacempty_dir: Making directory 2025/06/17 02:23:36 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:36 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:36 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:36 INFO : tictactoe: Making directory 2025/06/17 02:23:36 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:36 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:36 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:36 INFO : tictactoe/tictactoe: Making directory 2025/06/17 02:23:37 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:37 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/17 02:23:37 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:37 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/17 02:23:37 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:37 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:37 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for checks to finish 2025/06/17 02:23:37 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for transfers to finish 2025/06/17 02:23:38 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/17 02:23:38 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/17 02:23:38 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:38 INFO : toe/toe/toe.txt: Deleted 2025/06/17 02:23:38 INFO : toe/toe: Removing directory 2025/06/17 02:23:38 INFO : toe: Removing directory 2025/06/17 02:23:38 INFO : empty_dir: Removing directory 2025/06/17 02:23:38 DEBUG : Local file system at /tmp/rclone1191831557: deleted 3 directories --- PASS: TestMove (5.20s) === RUN TestTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:23:41 DEBUG : empty_dir: Making directory with metadata 2025/06/17 02:23:41 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/17 02:23:41 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:41 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:41 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:41 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:41 INFO : tictacempty_dir: Making directory 2025/06/17 02:23:41 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:41 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:41 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:41 INFO : tictactoe: Making directory 2025/06/17 02:23:42 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:42 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:42 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:42 INFO : tictactoe/tictactoe: Making directory 2025/06/17 02:23:42 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:42 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/17 02:23:42 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:42 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/17 02:23:42 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:42 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:42 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for checks to finish 2025/06/17 02:23:42 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for transfers to finish 2025/06/17 02:23:43 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/17 02:23:44 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/17 02:23:44 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:44 INFO : toe/toe/toe.txt: Deleted 2025/06/17 02:23:44 INFO : toe/toe: Removing directory 2025/06/17 02:23:44 INFO : toe: Removing directory 2025/06/17 02:23:44 INFO : empty_dir: Removing directory 2025/06/17 02:23:44 DEBUG : Local file system at /tmp/rclone1191831557: deleted 3 directories 2025/06/17 02:23:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/17 02:23:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:46 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt --- PASS: TestTransformFile (9.33s) === RUN TestManualTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:23:50 DEBUG : Reset feature "Copy" 2025/06/17 02:23:50 DEBUG : Reset feature "Move" 2025/06/17 02:23:50 DEBUG : Reset feature "Copy" 2025/06/17 02:23:50 DEBUG : Reset feature "Move" 2025/06/17 02:23:50 DEBUG : empty_dir: Making directory with metadata 2025/06/17 02:23:50 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/17 02:23:50 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:50 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:50 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:50 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:50 INFO : tictacempty_dir: Making directory 2025/06/17 02:23:51 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/17 02:23:51 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:51 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:51 INFO : tictactoe: Making directory 2025/06/17 02:23:51 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:51 DEBUG : toe: transformed to: tictactoe 2025/06/17 02:23:51 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:51 INFO : tictactoe/tictactoe: Making directory 2025/06/17 02:23:51 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/17 02:23:51 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/17 02:23:51 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:51 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/17 02:23:51 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:51 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:51 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for checks to finish 2025/06/17 02:23:51 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for transfers to finish 2025/06/17 02:23:52 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/17 02:23:53 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/17 02:23:53 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/17 02:23:53 INFO : toe/toe/toe.txt: Deleted 2025/06/17 02:23:53 INFO : toe/toe: Removing directory 2025/06/17 02:23:53 INFO : toe: Removing directory 2025/06/17 02:23:53 INFO : empty_dir: Removing directory 2025/06/17 02:23:53 DEBUG : Local file system at /tmp/rclone1191831557: deleted 3 directories 2025/06/17 02:23:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/17 02:23:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/17 02:23:56 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2025/06/17 02:23:57 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2025/06/17 02:23:57 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2025/06/17 02:23:57 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted --- PASS: TestManualTransformFile (9.81s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-nepomov8duge'", Local "Local file system at /tmp/rclone1191831557", Modify Window "1s" 2025/06/17 02:24:00 DEBUG : toe: transformed to: dG9l 2025/06/17 02:24:00 DEBUG : toe: transformed to: dG9l 2025/06/17 02:24:00 INFO : dG9l: Making directory 2025/06/17 02:24:00 DEBUG : toe: transformed to: dG9l 2025/06/17 02:24:00 DEBUG : toe: transformed to: dG9l 2025/06/17 02:24:00 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/06/17 02:24:00 INFO : dG9l/dG9l: Making directory 2025/06/17 02:24:01 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/06/17 02:24:01 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/06/17 02:24:01 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/06/17 02:24:01 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/17 02:24:01 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/06/17 02:24:01 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for checks to finish 2025/06/17 02:24:01 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Waiting for transfers to finish 2025/06/17 02:24:02 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2025/06/17 02:24:03 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/17 02:24:03 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/06/17 02:24:03 DEBUG : Waiting for deletions to finish 2025/06/17 02:24:03 DEBUG : dG9l: transformed to: toe 2025/06/17 02:24:03 DEBUG : dG9l: transformed to: toe 2025/06/17 02:24:03 DEBUG : toe: Directory modification time the same (differ by -608.186871ms, within tolerance 1s) 2025/06/17 02:24:03 DEBUG : Added delayed dir = "toe", newDst=toe 2025/06/17 02:24:03 DEBUG : dG9l: transformed to: toe 2025/06/17 02:24:03 DEBUG : dG9l/dG9l: transformed to: toe/toe 2025/06/17 02:24:03 INFO : toe/toe: Set directory modification time (using SetModTime) 2025/06/17 02:24:03 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2025/06/17 02:24:04 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2025/06/17 02:24:04 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2025/06/17 02:24:04 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2025/06/17 02:24:04 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2025/06/17 02:24:04 DEBUG : Local file system at /tmp/rclone1191831557: Waiting for checks to finish 2025/06/17 02:24:04 DEBUG : Local file system at /tmp/rclone1191831557: Waiting for transfers to finish 2025/06/17 02:24:04 DEBUG : Waiting for deletions to finish run.go:125: removing dir "dG9l/dG9l" failed - try 1/3: folder not empty --- PASS: TestBase64 (7.21s) FAIL 2025/06/17 02:24:07 DEBUG : OpenDrive root 'rclone-test-nepomov8duge': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestFixCase|TestManualTransformFile|TestMove|TestTransformFile)$'" - Finished ERROR in 53.683589577s (try 3/5): exit status 1: Failed [TestFixCase]