"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestMove|TestNothingToTransferWithEmptyDirs|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir|TestSyncIgnoreErrors|TestSyncOverlapWithFilter|TestTransformFile)$'" - Starting (try 2/5) 2025/06/18 01:32:46 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu" 2025/06/18 01:32:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/18 01:32:47 DEBUG : Starting OpenDrive session with ID: 58eefb7a6ca9df9058e6c76e18b3073507f78018f1777db23a62476596e8617c 2025/06/18 01:32:47 DEBUG : Creating backend with remote "/tmp/rclone2637583719" === RUN TestSyncIgnoreErrors run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:32:49 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/06/18 01:32:51 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/06/18 01:32:52 INFO : d: Making directory 2025/06/18 01:32:53 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/18 01:32:53 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/06/18 01:32:53 DEBUG : c/non empty space: Unchanged skipping 2025/06/18 01:32:53 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:32:53 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:32:54 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0 2025/06/18 01:32:55 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/06/18 01:32:55 INFO : a/potato2: Copied (new) 2025/06/18 01:32:55 DEBUG : Waiting for deletions to finish 2025/06/18 01:32:56 INFO : b/potato: Deleted 2025/06/18 01:32:56 INFO : d: Removing directory 2025/06/18 01:32:56 INFO : b: Removing directory 2025/06/18 01:32:56 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': deleted 2 directories fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure --- PASS: TestSyncIgnoreErrors (18.35s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:33:07 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/06/18 01:33:09 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/06/18 01:33:10 INFO : d: Making directory 2025/06/18 01:33:10 INFO : d/e: Making directory 2025/06/18 01:33:11 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/18 01:33:11 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/06/18 01:33:11 DEBUG : c/non empty space: Unchanged skipping 2025/06/18 01:33:12 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:33:12 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:33:12 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0 2025/06/18 01:33:13 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/06/18 01:33:13 INFO : a/potato2: Copied (new) 2025/06/18 01:33:13 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:13 INFO : b/potato: Deleted 2025/06/18 01:33:13 INFO : d/e: Removing directory 2025/06/18 01:33:14 INFO : d: Removing directory 2025/06/18 01:33:14 INFO : b: Removing directory 2025/06/18 01:33:14 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (10.75s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:33:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu/rclone-sync-test" 2025/06/18 01:33:17 DEBUG : Starting OpenDrive session with ID: c97a18bdae203df1d726068b8b49f655e51ee771bfb661b38eb55c97a3deeeb8 2025/06/18 01:33:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu/rclone-sync-test-include/layer2" 2025/06/18 01:33:18 DEBUG : Starting OpenDrive session with ID: 39621daaff43f10ffa249d232aee5d500a9995cb0be236fdfe216e4c880024df 2025/06/18 01:33:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu/rclone-sync-test-ignore-file" 2025/06/18 01:33:20 DEBUG : Starting OpenDrive session with ID: cae4ccfccb2ff15a68411c281a912a1aea3adf21eab4315d34afb1d0fd681538 2025/06/18 01:33:22 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2025/06/18 01:33:24 DEBUG : rclone-sync-test: Excluded 2025/06/18 01:33:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 01:33:24 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/06/18 01:33:24 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test': Waiting for checks to finish 2025/06/18 01:33:24 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test': Waiting for transfers to finish 2025/06/18 01:33:24 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:24 INFO : There was nothing to transfer 2025/06/18 01:33:25 DEBUG : rclone-sync-test: Excluded 2025/06/18 01:33:25 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 01:33:26 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/06/18 01:33:26 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:33:26 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:33:26 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:26 INFO : rclone-sync-test-include: Removing directory 2025/06/18 01:33:26 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': deleted 1 directories 2025/06/18 01:33:26 INFO : There was nothing to transfer 2025/06/18 01:33:27 DEBUG : rclone-sync-test: Excluded 2025/06/18 01:33:27 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 01:33:27 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/18 01:33:27 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/18 01:33:27 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:27 INFO : There was nothing to transfer 2025/06/18 01:33:27 DEBUG : rclone-sync-test: Excluded 2025/06/18 01:33:27 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 01:33:27 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:33:27 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:33:27 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:27 INFO : There was nothing to transfer 2025/06/18 01:33:28 DEBUG : Excluded 2025/06/18 01:33:28 DEBUG : rclone-sync-test: Excluded 2025/06/18 01:33:28 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 01:33:28 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/18 01:33:28 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/18 01:33:28 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:28 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (13.09s) === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:33:31 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/06/18 01:33:32 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/06/18 01:33:34 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/06/18 01:33:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu/dst" 2025/06/18 01:33:36 DEBUG : Starting OpenDrive session with ID: 3839c3060fd3054fb2bd7be77fe0cf38c908b8bce93e1b992c1842317ca4ae49 2025/06/18 01:33:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fesuzez2niwu/backup" 2025/06/18 01:33:36 DEBUG : Starting OpenDrive session with ID: 7557f0708229302d9d8af927245ba5859add0542d213a5ee4fda4fa5c5b88595 2025/06/18 01:33:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 01:33:37 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/18 01:33:37 DEBUG : two: Unchanged skipping 2025/06/18 01:33:37 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/dst': Waiting for checks to finish 2025/06/18 01:33:38 INFO : one: Moved (server-side) 2025/06/18 01:33:38 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/dst': Waiting for transfers to finish 2025/06/18 01:33:39 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/06/18 01:33:40 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/18 01:33:40 INFO : one: Copied (new) 2025/06/18 01:33:40 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:41 INFO : three.txt: Moved (server-side) 2025/06/18 01:33:41 INFO : three.txt: Moved into backup dir 2025/06/18 01:33:42 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/06/18 01:33:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 01:33:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/18 01:33:43 DEBUG : two: Unchanged skipping 2025/06/18 01:33:43 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/dst': Waiting for checks to finish 2025/06/18 01:33:44 INFO : one: Deleted 2025/06/18 01:33:44 INFO : one: Moved (server-side) 2025/06/18 01:33:44 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu/dst': Waiting for transfers to finish 2025/06/18 01:33:45 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/06/18 01:33:46 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/18 01:33:46 INFO : one: Copied (new) 2025/06/18 01:33:46 DEBUG : Waiting for deletions to finish 2025/06/18 01:33:46 INFO : three.txt: Deleted 2025/06/18 01:33:46 INFO : three.txt: Moved (server-side) 2025/06/18 01:33:46 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (19.22s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:33:49 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 01:33:49 INFO : sub dir: Making directory 2025/06/18 01:33:49 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (15.33s) === RUN TestMove run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:34:04 DEBUG : empty_dir: Making directory with metadata 2025/06/18 01:34:04 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/18 01:34:04 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:04 INFO : tictacempty_dir: Making directory 2025/06/18 01:34:05 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:05 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:05 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:05 INFO : tictactoe: Making directory 2025/06/18 01:34:05 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:05 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:05 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:05 INFO : tictactoe/tictactoe: Making directory 2025/06/18 01:34:05 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:05 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/18 01:34:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:05 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/18 01:34:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:05 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:34:05 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:34:06 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/18 01:34:07 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/18 01:34:07 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:07 INFO : toe/toe/toe.txt: Deleted 2025/06/18 01:34:07 INFO : toe/toe: Removing directory 2025/06/18 01:34:07 INFO : toe: Removing directory 2025/06/18 01:34:07 INFO : empty_dir: Removing directory 2025/06/18 01:34:07 DEBUG : Local file system at /tmp/rclone2637583719: deleted 3 directories --- PASS: TestMove (5.41s) === RUN TestTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:34:09 DEBUG : empty_dir: Making directory with metadata 2025/06/18 01:34:09 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/18 01:34:09 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:10 INFO : tictacempty_dir: Making directory 2025/06/18 01:34:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:10 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:10 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:10 INFO : tictactoe: Making directory 2025/06/18 01:34:10 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:10 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:10 INFO : tictactoe/tictactoe: Making directory 2025/06/18 01:34:11 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:11 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/18 01:34:11 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:11 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/18 01:34:11 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:11 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:11 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:34:11 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:34:11 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/18 01:34:12 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/18 01:34:12 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:12 INFO : toe/toe/toe.txt: Deleted 2025/06/18 01:34:12 INFO : toe/toe: Removing directory 2025/06/18 01:34:12 INFO : toe: Removing directory 2025/06/18 01:34:12 INFO : empty_dir: Removing directory 2025/06/18 01:34:12 DEBUG : Local file system at /tmp/rclone2637583719: deleted 3 directories 2025/06/18 01:34:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/18 01:34:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:14 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt --- PASS: TestTransformFile (8.11s) === RUN TestManualTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:34:18 DEBUG : Reset feature "Copy" 2025/06/18 01:34:18 DEBUG : Reset feature "Move" 2025/06/18 01:34:18 DEBUG : Reset feature "Copy" 2025/06/18 01:34:18 DEBUG : Reset feature "Move" 2025/06/18 01:34:18 DEBUG : empty_dir: Making directory with metadata 2025/06/18 01:34:18 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/06/18 01:34:18 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:18 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:18 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:18 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:18 INFO : tictacempty_dir: Making directory 2025/06/18 01:34:18 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/06/18 01:34:18 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:18 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:18 INFO : tictactoe: Making directory 2025/06/18 01:34:18 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:18 DEBUG : toe: transformed to: tictactoe 2025/06/18 01:34:18 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:18 INFO : tictactoe/tictactoe: Making directory 2025/06/18 01:34:19 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/06/18 01:34:19 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/06/18 01:34:19 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:19 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/18 01:34:19 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:19 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:19 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:34:19 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:34:20 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/06/18 01:34:20 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/18 01:34:20 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/06/18 01:34:20 INFO : toe/toe/toe.txt: Deleted 2025/06/18 01:34:20 INFO : toe/toe: Removing directory 2025/06/18 01:34:20 INFO : toe: Removing directory 2025/06/18 01:34:20 INFO : empty_dir: Removing directory 2025/06/18 01:34:20 DEBUG : Local file system at /tmp/rclone2637583719: deleted 3 directories 2025/06/18 01:34:21 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/18 01:34:21 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:21 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:21 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/06/18 01:34:23 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2025/06/18 01:34:24 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2025/06/18 01:34:24 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2025/06/18 01:34:24 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure --- PASS: TestManualTransformFile (17.57s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-fesuzez2niwu'", Local "Local file system at /tmp/rclone2637583719", Modify Window "1s" 2025/06/18 01:34:35 DEBUG : toe: transformed to: dG9l 2025/06/18 01:34:35 DEBUG : toe: transformed to: dG9l 2025/06/18 01:34:35 INFO : dG9l: Making directory 2025/06/18 01:34:36 DEBUG : toe: transformed to: dG9l 2025/06/18 01:34:36 DEBUG : toe: transformed to: dG9l 2025/06/18 01:34:36 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/06/18 01:34:36 INFO : dG9l/dG9l: Making directory 2025/06/18 01:34:36 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/06/18 01:34:36 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/06/18 01:34:36 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/06/18 01:34:36 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/06/18 01:34:36 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/06/18 01:34:36 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for checks to finish 2025/06/18 01:34:36 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Waiting for transfers to finish 2025/06/18 01:34:37 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2025/06/18 01:34:38 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/18 01:34:38 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/06/18 01:34:38 DEBUG : Waiting for deletions to finish 2025/06/18 01:34:38 DEBUG : dG9l: transformed to: toe 2025/06/18 01:34:38 DEBUG : dG9l: transformed to: toe 2025/06/18 01:34:38 DEBUG : toe: Directory modification time the same (differ by -384.199126ms, within tolerance 1s) 2025/06/18 01:34:38 DEBUG : Added delayed dir = "toe", newDst=toe 2025/06/18 01:34:39 DEBUG : dG9l: transformed to: toe 2025/06/18 01:34:39 DEBUG : dG9l/dG9l: transformed to: toe/toe 2025/06/18 01:34:39 INFO : toe/toe: Set directory modification time (using SetModTime) 2025/06/18 01:34:39 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2025/06/18 01:34:39 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2025/06/18 01:34:39 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2025/06/18 01:34:39 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2025/06/18 01:34:39 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2025/06/18 01:34:39 DEBUG : Local file system at /tmp/rclone2637583719: Waiting for checks to finish 2025/06/18 01:34:39 DEBUG : Local file system at /tmp/rclone2637583719: Waiting for transfers to finish 2025/06/18 01:34:39 DEBUG : Waiting for deletions to finish --- PASS: TestBase64 (5.79s) PASS 2025/06/18 01:34:41 DEBUG : OpenDrive root 'rclone-test-fesuzez2niwu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestMove|TestNothingToTransferWithEmptyDirs|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir|TestSyncIgnoreErrors|TestSyncOverlapWithFilter|TestTransformFile)$'" - Finished OK in 1m55.101117261s (try 2/5)