"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestAllTag|TestBase64|TestConflicting|TestDirTag|TestManualTransformFile|TestMove|TestRunTwice|TestServerSideMove|TestSyncBackupDir|TestSyncCompareDest|TestTransformFile)$'" - Starting (try 2/5) 2025/09/10 04:42:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-roqamiq4neto" 2025/09/10 04:42:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/10 04:42:06 DEBUG : Starting OpenDrive session with ID: a1f7310d82aeaed4f9b0b1fcac8698b345b451395d5b3ecbfd62e078e767a2e2 2025/09/10 04:42:06 DEBUG : Creating backend with remote "/tmp/rclone693051979" === RUN TestServerSideMove run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:42:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-radaxac8kogi" 2025/09/10 04:42:07 DEBUG : Starting OpenDrive session with ID: 5915a63cfc1945ddd8c8dfd8f277daf29c75ed0eb6ec34b66b3a9796b67b8f2f 2025/09/10 04:42:08 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/09/10 04:42:11 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/09/10 04:42:12 DEBUG : potato3: Uploading chunk 0, size=68, remain=0 sync_test.go:1735: Server side move (if possible) OpenDrive root 'rclone-test-roqamiq4neto' -> OpenDrive root 'rclone-test-radaxac8kogi' 2025/09/10 04:42:15 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/09/10 04:42:17 DEBUG : potato3: Uploading chunk 0, size=60, remain=0 2025/09/10 04:42:18 DEBUG : OpenDrive root 'rclone-test-radaxac8kogi': Using server-side directory move 2025/09/10 04:42:18 INFO : OpenDrive root 'rclone-test-radaxac8kogi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/10 04:42:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/10 04:42:18 DEBUG : OpenDrive root 'rclone-test-radaxac8kogi': Waiting for checks to finish 2025/09/10 04:42:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/10 04:42:18 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/10 04:42:18 DEBUG : empty space: Unchanged skipping 2025/09/10 04:42:18 INFO : potato3: Deleted 2025/09/10 04:42:18 INFO : empty space: Deleted 2025/09/10 04:42:18 DEBUG : OpenDrive root 'rclone-test-radaxac8kogi': Waiting for transfers to finish 2025/09/10 04:42:18 INFO : potato2: Moved (server-side) 2025/09/10 04:42:19 INFO : potato3: Moved (server-side) 2025/09/10 04:42:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xowuhuz0jeka" 2025/09/10 04:42:20 DEBUG : Starting OpenDrive session with ID: 348200e47e99bd5c2e24377ea3b5f71bb7d61d726c7c1ac77693e20891d19316 2025/09/10 04:42:20 DEBUG : OpenDrive root 'rclone-test-xowuhuz0jeka': Using server-side directory move 2025/09/10 04:42:20 DEBUG : same parent dir (0) - using folder/rename instead of move_copy 2025/09/10 04:42:20 INFO : OpenDrive root 'rclone-test-xowuhuz0jeka': Server side directory move succeeded 2025/09/10 04:42:21 ERROR : error listing: directory not found 2025/09/10 04:42:21 DEBUG : OpenDrive root 'rclone-test-xowuhuz0jeka': Purge remote 2025/09/10 04:42:21 DEBUG : OpenDrive root 'rclone-test-radaxac8kogi': Purge remote 2025/09/10 04:42:21 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (15.27s) === RUN TestSyncCompareDest run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:42:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-roqamiq4neto/dst" 2025/09/10 04:42:22 DEBUG : Starting OpenDrive session with ID: 8b47bf7b8708015437f0c0ac1f91d0f29c5fffa042ecc6e6f620beb9cd9cdc2a 2025/09/10 04:42:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-roqamiq4neto/CompareDest" 2025/09/10 04:42:24 DEBUG : Starting OpenDrive session with ID: 6129468625e6d8fc5a270b31f744fcfe93a016abb1156d5e71abc5119942bff6 2025/09/10 04:42:25 DEBUG : one: Need to transfer - File not found at Destination 2025/09/10 04:42:25 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:25 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:26 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/09/10 04:42:27 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/09/10 04:42:27 INFO : one: Copied (new) 2025/09/10 04:42:27 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:28 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:28 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:28 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/09/10 04:42:30 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/09/10 04:42:30 INFO : one: Copied (replaced existing) 2025/09/10 04:42:30 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:30 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/09/10 04:42:32 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0 2025/09/10 04:42:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:34 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:34 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:34 DEBUG : one: Destination found in --compare-dest, skipping 2025/09/10 04:42:34 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:34 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:34 INFO : There was nothing to transfer 2025/09/10 04:42:36 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0 2025/09/10 04:42:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:37 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:37 DEBUG : two: Destination found in --compare-dest, skipping 2025/09/10 04:42:37 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:38 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:38 DEBUG : one: Destination found in --compare-dest, skipping 2025/09/10 04:42:38 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:38 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:38 INFO : There was nothing to transfer 2025/09/10 04:42:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:38 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:38 DEBUG : two: Destination found in --compare-dest, skipping 2025/09/10 04:42:38 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:38 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:38 DEBUG : one: Destination found in --compare-dest, skipping 2025/09/10 04:42:38 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:38 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:38 INFO : There was nothing to transfer sync_test.go:2205: No hash on uploaded file so skipping compare timestamp test 2025/09/10 04:42:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:40 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/09/10 04:42:40 DEBUG : two: Need to transfer - File not found at Destination 2025/09/10 04:42:40 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:40 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/10 04:42:40 DEBUG : one: Destination found in --compare-dest, skipping 2025/09/10 04:42:40 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:41 DEBUG : two: Uploading chunk 0, size=5, remain=0 2025/09/10 04:42:42 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2025/09/10 04:42:42 INFO : two: Copied (new) 2025/09/10 04:42:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (22.27s) === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:42:45 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/09/10 04:42:47 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/09/10 04:42:49 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/09/10 04:42:50 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-roqamiq4neto/dst" 2025/09/10 04:42:51 DEBUG : Starting OpenDrive session with ID: 80acb4c1fe3d375df306196bcd9c8680202d5e24ad8e80f4609731b7d05bd76b 2025/09/10 04:42:51 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-roqamiq4neto/backup" 2025/09/10 04:42:52 DEBUG : Starting OpenDrive session with ID: fdfb71852e744151cdccea32dd4a25a9e9fabc2dabad262910d87bd9ee86eeea 2025/09/10 04:42:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/10 04:42:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/10 04:42:53 DEBUG : two: Unchanged skipping 2025/09/10 04:42:53 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:54 INFO : one: Moved (server-side) 2025/09/10 04:42:54 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:42:55 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/09/10 04:42:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/09/10 04:42:56 INFO : one: Copied (new) 2025/09/10 04:42:56 DEBUG : Waiting for deletions to finish 2025/09/10 04:42:56 INFO : three.txt: Moved (server-side) 2025/09/10 04:42:56 INFO : three.txt: Moved into backup dir 2025/09/10 04:42:57 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/09/10 04:42:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/09/10 04:42:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/10 04:42:59 DEBUG : two: Unchanged skipping 2025/09/10 04:42:59 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for checks to finish 2025/09/10 04:42:59 INFO : one: Deleted 2025/09/10 04:43:00 INFO : one: Moved (server-side) 2025/09/10 04:43:00 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto/dst': Waiting for transfers to finish 2025/09/10 04:43:00 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/09/10 04:43:01 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/09/10 04:43:01 INFO : one: Copied (new) 2025/09/10 04:43:01 DEBUG : Waiting for deletions to finish 2025/09/10 04:43:02 INFO : three.txt: Deleted 2025/09/10 04:43:02 INFO : three.txt: Moved (server-side) 2025/09/10 04:43:02 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (20.49s) === RUN TestDirTag run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:05 DEBUG : empty_dir: Making directory with metadata 2025/09/10 04:43:05 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/10 04:43:05 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:05 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:05 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:05 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:05 INFO : tictacempty_dir: Making directory 2025/09/10 04:43:05 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:05 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:05 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:05 INFO : tictactoe: Making directory 2025/09/10 04:43:05 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:05 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:05 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:05 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:06 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:06 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:06 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:06 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:06 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:06 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:07 DEBUG : tictactoe/tictactoe/toe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:07 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:07 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:07 DEBUG : Waiting for deletions to finish --- PASS: TestDirTag (5.41s) === RUN TestAllTag run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:10 DEBUG : empty_dir: Making directory with metadata 2025/09/10 04:43:10 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/10 04:43:10 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:10 INFO : tictacempty_dir: Making directory 2025/09/10 04:43:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:10 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:10 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:10 INFO : tictactoe: Making directory 2025/09/10 04:43:11 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:11 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:11 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:11 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:11 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:11 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/09/10 04:43:11 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:11 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:11 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:11 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:11 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:12 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:13 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:13 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:13 DEBUG : Waiting for deletions to finish 2025/09/10 04:43:14 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:14 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:14 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:14 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:14 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:14 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:14 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/09/10 04:43:14 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:14 DEBUG : tictactoe/tictactoe/tictactoe.txt: OK 2025/09/10 04:43:14 NOTICE: OpenDrive root 'rclone-test-roqamiq4neto': 0 differences found 2025/09/10 04:43:14 NOTICE: OpenDrive root 'rclone-test-roqamiq4neto': 1 matching files --- PASS: TestAllTag (6.06s) === RUN TestRunTwice run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:16 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:16 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:16 INFO : tictactoe: Making directory 2025/09/10 04:43:17 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:17 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:17 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:17 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:17 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:17 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:17 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:17 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:17 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:17 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:18 DEBUG : tictactoe/tictactoe/toe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:19 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:19 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:19 DEBUG : Waiting for deletions to finish 2025/09/10 04:43:19 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:19 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:19 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:19 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:20 DEBUG : toe/toe/toe.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/10 04:43:20 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:20 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2025/09/10 04:43:20 DEBUG : toe/toe/toe.txt: Unchanged skipping 2025/09/10 04:43:20 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:20 DEBUG : Waiting for deletions to finish --- PASS: TestRunTwice (5.81s) === RUN TestConflicting run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:22 INFO : toe: Making directory 2025/09/10 04:43:22 INFO : toe/toe: Making directory 2025/09/10 04:43:23 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2025/09/10 04:43:23 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:23 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:23 DEBUG : toe/toe/toe: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:25 DEBUG : toe/toe/toe: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:25 INFO : toe/toe/toe: Copied (new) 2025/09/10 04:43:25 DEBUG : Waiting for deletions to finish --- PASS: TestConflicting (4.85s) === RUN TestMove run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:27 DEBUG : empty_dir: Making directory with metadata 2025/09/10 04:43:27 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/10 04:43:27 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:27 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:27 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:27 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:27 INFO : tictacempty_dir: Making directory 2025/09/10 04:43:27 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:27 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:27 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:27 INFO : tictactoe: Making directory 2025/09/10 04:43:28 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:28 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:28 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:28 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:28 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:28 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/09/10 04:43:28 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:28 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:28 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:28 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:28 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:28 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:29 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:30 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:30 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:30 INFO : toe/toe/toe.txt: Deleted 2025/09/10 04:43:30 INFO : toe/toe: Removing directory 2025/09/10 04:43:30 INFO : toe: Removing directory 2025/09/10 04:43:30 INFO : empty_dir: Removing directory 2025/09/10 04:43:30 DEBUG : Local file system at /tmp/rclone693051979: deleted 3 directories fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:411 Error: Should be true Test: TestMove Messages: tictactoe/tictactoe/tictactoe.txt: Modification time difference too big |-215640h38m22.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-09-10 04:43:29 +0000 UTC) (precision 1s) run.go:125: removing dir "tictactoe/tictactoe" failed - try 1/3: folder not empty --- FAIL: TestMove (6.70s) === RUN TestTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:33 DEBUG : empty_dir: Making directory with metadata 2025/09/10 04:43:33 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/10 04:43:33 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:33 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:33 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:34 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:34 INFO : tictacempty_dir: Making directory 2025/09/10 04:43:34 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:34 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:34 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:34 INFO : tictactoe: Making directory 2025/09/10 04:43:34 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:34 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:34 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:34 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:35 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:35 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/09/10 04:43:35 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:35 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:35 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:35 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:35 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:35 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:35 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:36 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:36 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:36 INFO : toe/toe/toe.txt: Deleted 2025/09/10 04:43:36 INFO : toe/toe: Removing directory 2025/09/10 04:43:36 INFO : toe: Removing directory 2025/09/10 04:43:36 INFO : empty_dir: Removing directory 2025/09/10 04:43:36 DEBUG : Local file system at /tmp/rclone693051979: deleted 3 directories 2025/09/10 04:43:37 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/10 04:43:37 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:37 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:37 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:38 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt --- PASS: TestTransformFile (8.45s) === RUN TestManualTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:42 DEBUG : Reset feature "Copy" 2025/09/10 04:43:42 DEBUG : Reset feature "Move" 2025/09/10 04:43:42 DEBUG : Reset feature "Copy" 2025/09/10 04:43:42 DEBUG : Reset feature "Move" 2025/09/10 04:43:42 DEBUG : empty_dir: Making directory with metadata 2025/09/10 04:43:42 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/10 04:43:42 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:42 INFO : tictacempty_dir: Making directory 2025/09/10 04:43:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/09/10 04:43:42 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:42 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:42 INFO : tictactoe: Making directory 2025/09/10 04:43:43 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:43 DEBUG : toe: transformed to: tictactoe 2025/09/10 04:43:43 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:43 INFO : tictactoe/tictactoe: Making directory 2025/09/10 04:43:43 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/09/10 04:43:43 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/09/10 04:43:43 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:43 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:43 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:43 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:43 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:43 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:44 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:45 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/10 04:43:45 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/09/10 04:43:45 INFO : toe/toe/toe.txt: Deleted 2025/09/10 04:43:45 INFO : toe/toe: Removing directory 2025/09/10 04:43:45 INFO : toe: Removing directory 2025/09/10 04:43:45 INFO : empty_dir: Removing directory 2025/09/10 04:43:45 DEBUG : Local file system at /tmp/rclone693051979: deleted 3 directories 2025/09/10 04:43:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/10 04:43:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/09/10 04:43:47 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2025/09/10 04:43:49 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2025/09/10 04:43:49 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2025/09/10 04:43:49 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted --- PASS: TestManualTransformFile (10.65s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-roqamiq4neto'", Local "Local file system at /tmp/rclone693051979", Modify Window "1s" 2025/09/10 04:43:52 DEBUG : toe: transformed to: dG9l 2025/09/10 04:43:53 DEBUG : toe: transformed to: dG9l 2025/09/10 04:43:53 INFO : dG9l: Making directory 2025/09/10 04:43:53 DEBUG : toe: transformed to: dG9l 2025/09/10 04:43:53 DEBUG : toe: transformed to: dG9l 2025/09/10 04:43:53 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/09/10 04:43:53 INFO : dG9l/dG9l: Making directory 2025/09/10 04:43:53 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/09/10 04:43:53 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/09/10 04:43:53 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/09/10 04:43:53 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/09/10 04:43:53 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/09/10 04:43:53 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for checks to finish 2025/09/10 04:43:53 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Waiting for transfers to finish 2025/09/10 04:43:53 ERROR : toe/toe/toe.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404) 2025/09/10 04:43:53 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/09/10 04:43:53 ERROR : OpenDrive root 'rclone-test-roqamiq4neto': not deleting files as there were IO errors 2025/09/10 04:43:53 ERROR : OpenDrive root 'rclone-test-roqamiq4neto': not deleting directories as there were IO errors sync_transform_test.go:481: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:481 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestBase64 --- FAIL: TestBase64 (1.37s) FAIL 2025/09/10 04:43:54 DEBUG : OpenDrive root 'rclone-test-roqamiq4neto': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestAllTag|TestBase64|TestConflicting|TestDirTag|TestManualTransformFile|TestMove|TestRunTwice|TestServerSideMove|TestSyncBackupDir|TestSyncCompareDest|TestTransformFile)$'" - Finished ERROR in 1m48.843275327s (try 2/5): exit status 1: Failed [TestMove TestBase64]