"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncRemoteLocal$/^extended_filenames$|^TestBisyncRemoteRemote$/^max_delete_path1$'" - Starting (try 3/5) 2025/05/06 07:21:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-ceheser9yote" 2025/05/06 07:21:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/06 07:21:56 DEBUG : Starting OpenDrive session with ID: ddf9c3ab606a30de1e2bd243a436331343e4cafbc48bd219ab78aba05ab094ac 2025/05/06 07:21:56 DEBUG : Creating backend with remote "/tmp/rclone3778041611" === RUN TestBisyncRemoteLocal 2025/05/06 07:21:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dixemoj0segi" 2025/05/06 07:21:57 DEBUG : Starting OpenDrive session with ID: bd79616027ee8e44d7389c25ec063450094957e3da0dd396f7f0f5278406fa3a 2025/05/06 07:21:57 NOTICE: remote: TestOpenDrive:rclone-test-dixemoj0segi === RUN TestBisyncRemoteLocal/extended_filenames 2025/05/06 07:21:57 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dixemoj0segi/072157la" 2025/05/06 07:21:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/06 07:21:57 DEBUG : Starting OpenDrive session with ID: 9751b095a606394d7dac0ac3f26072214135ba28ddc5adf67bc59a622227ed9a 2025/05/06 07:21:58 INFO : path1: Making directory 2025/05/06 07:21:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dixemoj0segi/072157la/extended_filenames/path1" 2025/05/06 07:21:59 DEBUG : Starting OpenDrive session with ID: 729544486a11b0cf0e6f184b8ea5b22a04a2fea95bfd2037d18e332b7b5fb174 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/tmp/072157la" 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/tmp/072157la/extended_filenames/path2" 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/initial" 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/tmp/072157la/initdir/test_extended_filenames-minupac5" 2025/05/06 07:22:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_: Making directory with metadata 2025/05/06 07:22:00 INFO : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/05/06 07:22:00 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_ 2025/05/06 07:22:00 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:00 DEBUG : file1 - Copy (2).txt.5028a97.partial: renamed to: file1 - Copy (2).txt 2025/05/06 07:22:00 INFO : file1 - Copy (2).txt: Copied (new) 2025/05/06 07:22:00 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:00 DEBUG : file1 - Copy.txt.5028a97.partial: renamed to: file1 - Copy.txt 2025/05/06 07:22:00 INFO : file1 - Copy.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:00 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2025/05/06 07:22:00 INFO : file1.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:00 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:00 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST 2025/05/06 07:22:00 INFO : RCLONE_TEST: Copied (new) 2025/05/06 07:22:00 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:00 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:00 DEBUG : filename_contains_ě_.txt.480f4fd7.partial: renamed to: filename_contains_ě_.txt 2025/05/06 07:22:00 INFO : filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : Русский.txt.5028a97.partial: renamed to: Русский.txt 2025/05/06 07:22:00 INFO : Русский.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : file_enconde_mañana_funcionará.txt.5028a97.partial: renamed to: file_enconde_mañana_funcionará.txt 2025/05/06 07:22:00 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:00 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: filename_contains_ࢺ_.txt 2025/05/06 07:22:00 INFO : filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : Local file system at /tmp/072157la/initdir/test_extended_filenames-minupac5: Waiting for checks to finish 2025/05/06 07:22:00 DEBUG : Local file system at /tmp/072157la/initdir/test_extended_filenames-minupac5: Waiting for transfers to finish 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt 2025/05/06 07:22:00 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:00 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt 2025/05/06 07:22:00 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:00 INFO : subdir_with_ࢺ_: Set directory modification time (using SetModTime) 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/modfiles" 2025/05/06 07:22:00 DEBUG : Creating backend with remote "/tmp/072157la/datadir/test_extended_filenames-zoludep0" 2025/05/06 07:22:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:00 DEBUG : Local file system at /tmp/072157la/datadir/test_extended_filenames-zoludep0: Waiting for checks to finish 2025/05/06 07:22:00 DEBUG : Local file system at /tmp/072157la/datadir/test_extended_filenames-zoludep0: Waiting for transfers to finish 2025/05/06 07:22:00 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/05/06 07:22:00 DEBUG : file1.txt.f73bbb59.partial: renamed to: file1.txt 2025/05/06 07:22:00 INFO : file1.txt: Copied (new) 2025/05/06 07:22:00 DEBUG : file2.txt: md5 = 40b811fb5009223b6da573f169619d8e OK 2025/05/06 07:22:00 DEBUG : file2.txt.761be064.partial: renamed to: file2.txt 2025/05/06 07:22:00 INFO : file2.txt: Copied (new) 2025/05/06 07:22:01 ERROR : : error listing: directory not found 2025/05/06 07:22:01 ERROR : : error listing: directory not found 2025/05/06 07:22:01 NOTICE: checking initFs Local file system at /tmp/072157la/initdir/test_extended_filenames-minupac5 2025/05/06 07:22:01 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/05/06 07:22:01 INFO : subdir_with_ࢺ_: Making directory 2025/05/06 07:22:02 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:02 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:02 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:02 DEBUG : OpenDrive root 'rclone-test-dixemoj0segi/072157la/extended_filenames/path1': Waiting for checks to finish 2025/05/06 07:22:02 DEBUG : OpenDrive root 'rclone-test-dixemoj0segi/072157la/extended_filenames/path1': Waiting for transfers to finish 2025/05/06 07:22:03 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:03 INFO : file1 - Copy.txt: Copied (new) 2025/05/06 07:22:04 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:04 INFO : file1 - Copy (2).txt: Copied (new) 2025/05/06 07:22:04 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:04 INFO : file1.txt: Copied (new) 2025/05/06 07:22:04 DEBUG : filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0 2025/05/06 07:22:05 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:05 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2025/05/06 07:22:05 DEBUG : filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0 2025/05/06 07:22:05 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0 2025/05/06 07:22:05 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:05 INFO : filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:06 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:06 INFO : filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:06 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:06 INFO : Русский.txt: Copied (new) 2025/05/06 07:22:06 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0 2025/05/06 07:22:07 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0 2025/05/06 07:22:07 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:07 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:08 INFO : RCLONE_TEST: Copied (new) 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:08 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:08 NOTICE: checking Path1 OpenDrive root 'rclone-test-dixemoj0segi/072157la/extended_filenames/path1' 2025/05/06 07:22:08 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_: Making directory with metadata 2025/05/06 07:22:08 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:08 DEBUG : file1 - Copy (2).txt.5028a97.partial: renamed to: file1 - Copy (2).txt 2025/05/06 07:22:08 INFO : file1 - Copy (2).txt: Copied (new) 2025/05/06 07:22:08 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:08 DEBUG : file1 - Copy.txt.5028a97.partial: renamed to: file1 - Copy.txt 2025/05/06 07:22:08 INFO : file1 - Copy.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:08 DEBUG : file_enconde_mañana_funcionará.txt.5028a97.partial: renamed to: file_enconde_mañana_funcionará.txt 2025/05/06 07:22:08 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:08 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2025/05/06 07:22:08 INFO : file1.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:08 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST 2025/05/06 07:22:08 INFO : RCLONE_TEST: Copied (new) 2025/05/06 07:22:08 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:08 DEBUG : filename_contains_ě_.txt.480f4fd7.partial: renamed to: filename_contains_ě_.txt 2025/05/06 07:22:08 INFO : filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:08 DEBUG : filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: filename_contains_ࢺ_.txt 2025/05/06 07:22:08 INFO : filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:08 INFO : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/05/06 07:22:08 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_ 2025/05/06 07:22:08 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:08 DEBUG : Local file system at /tmp/072157la/extended_filenames/path2: Waiting for checks to finish 2025/05/06 07:22:08 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:08 DEBUG : Русский.txt.5028a97.partial: renamed to: Русский.txt 2025/05/06 07:22:08 INFO : Русский.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : Local file system at /tmp/072157la/extended_filenames/path2: Waiting for transfers to finish 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt 2025/05/06 07:22:08 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/05/06 07:22:08 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt 2025/05/06 07:22:08 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2025/05/06 07:22:08 INFO : subdir_with_ࢺ_: Set directory modification time (using SetModTime) 2025/05/06 07:22:08 NOTICE: checking path2 Local file system at /tmp/072157la/extended_filenames/path2 2025/05/06 07:22:08 NOTICE: (01) : test extended-filenames 2025/05/06 07:22:08 NOTICE: (02) : fix-names TestOpenDrive:rclone-test-dixemoj0segi/072157la/extended_filenames/path1/subdir_with_ࢺ_/ 2025/05/06 07:22:09 NOTICE: (03) : fix-names /tmp/072157la/extended_filenames/path2/subdir_with_ࢺ_/ 2025/05/06 07:22:09 NOTICE: (04) : fix-names TestOpenDrive:rclone-test-dixemoj0segi/072157la/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt 2025/05/06 07:22:10 NOTICE: (05) : fix-names /tmp/072157la/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt 2025/05/06 07:22:10 NOTICE: (06) : fix-names TestOpenDrive:rclone-test-dixemoj0segi/072157la/extended_filenames/path1/Русский.txt 2025/05/06 07:22:11 NOTICE: (07) : fix-names /tmp/072157la/extended_filenames/path2/Русский.txt 2025/05/06 07:22:11 NOTICE: (08) : fix-names TestOpenDrive:rclone-test-dixemoj0segi/072157la/extended_filenames/path1/file_enconde_mañana_funcionará.txt 2025/05/06 07:22:12 NOTICE: (09) : fix-names /tmp/072157la/extended_filenames/path2/file_enconde_mañana_funcionará.txt 2025/05/06 07:22:12 NOTICE: (10) : test initial bisync 2025/05/06 07:22:12 NOTICE: (11) : bisync resync bisync_test.go:1002: Fs is incapable of running test, skipping: extended_filenames (expected: ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö (NFD) actual: upload failed (failed to create file: Filename is invalid or cannot contain any of the following characters \ / : * ? " < > | or 4-byte UTF-8 (Error 400))) (fs: OpenDrive root 'rclone-test-dixemoj0segi/072157la/extended_filenames/path1') 2025/05/06 07:22:14 DEBUG : OpenDrive root 'rclone-test-dixemoj0segi': Purge remote --- PASS: TestBisyncRemoteLocal (18.69s) --- SKIP: TestBisyncRemoteLocal/extended_filenames (17.25s) === RUN TestBisyncRemoteRemote 2025/05/06 07:22:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yefekal4wuxu" 2025/05/06 07:22:15 DEBUG : Starting OpenDrive session with ID: 9f70a6e3db0ebf21689b3f2430d722047f3c77c0f956c8b752d5d0f5bacf1fea 2025/05/06 07:22:16 NOTICE: remote: TestOpenDrive:rclone-test-yefekal4wuxu === RUN TestBisyncRemoteRemote/max_delete_path1 2025/05/06 07:22:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yefekal4wuxu/072216se" 2025/05/06 07:22:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/06 07:22:16 DEBUG : Starting OpenDrive session with ID: a5502b58970c88cfb0ea73a038ddedb2319314e4be759e7f5c4016b5ffe661df 2025/05/06 07:22:16 INFO : path1: Making directory 2025/05/06 07:22:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1" 2025/05/06 07:22:18 DEBUG : Starting OpenDrive session with ID: b0b5a7a62d8c3b822aafa272d59d63c574c8cda6582fbbc9a338e1a4604ae35f 2025/05/06 07:22:19 INFO : path2: Making directory 2025/05/06 07:22:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2" 2025/05/06 07:22:20 DEBUG : Starting OpenDrive session with ID: 546e5427cb02c16b05483e98f5d8ea0f9b8a31f7393507cb27319aad24f72002 2025/05/06 07:22:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/initial" 2025/05/06 07:22:21 DEBUG : Creating backend with remote "/tmp/072216se/initdir/test_max_delete_path1-hixurub8" 2025/05/06 07:22:21 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file9.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2025/05/06 07:22:21 INFO : file1.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : Local file system at /tmp/072216se/initdir/test_max_delete_path1-hixurub8: Waiting for checks to finish 2025/05/06 07:22:21 DEBUG : Local file system at /tmp/072216se/initdir/test_max_delete_path1-hixurub8: Waiting for transfers to finish 2025/05/06 07:22:21 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt 2025/05/06 07:22:21 INFO : file3.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt 2025/05/06 07:22:21 INFO : file4.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt 2025/05/06 07:22:21 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt 2025/05/06 07:22:21 INFO : file7.txt: Copied (new) 2025/05/06 07:22:21 INFO : file6.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt 2025/05/06 07:22:21 INFO : file2.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt 2025/05/06 07:22:21 INFO : file5.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:21 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt 2025/05/06 07:22:21 INFO : file8.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:21 DEBUG : file9.txt.cf9a93ac.partial: renamed to: file9.txt 2025/05/06 07:22:21 INFO : file9.txt: Copied (new) 2025/05/06 07:22:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/modfiles" 2025/05/06 07:22:21 DEBUG : Creating backend with remote "/tmp/072216se/datadir/test_max_delete_path1-fopurow6" 2025/05/06 07:22:21 DEBUG : hold.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:21 DEBUG : Local file system at /tmp/072216se/datadir/test_max_delete_path1-fopurow6: Waiting for checks to finish 2025/05/06 07:22:21 DEBUG : Local file system at /tmp/072216se/datadir/test_max_delete_path1-fopurow6: Waiting for transfers to finish 2025/05/06 07:22:21 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2025/05/06 07:22:21 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt 2025/05/06 07:22:21 INFO : hold.txt: Copied (new) 2025/05/06 07:22:22 ERROR : : error listing: directory not found 2025/05/06 07:22:22 ERROR : : error listing: directory not found 2025/05/06 07:22:22 NOTICE: checking initFs Local file system at /tmp/072216se/initdir/test_max_delete_path1-hixurub8 2025/05/06 07:22:22 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : file9.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:22 DEBUG : OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1': Waiting for checks to finish 2025/05/06 07:22:22 DEBUG : OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1': Waiting for transfers to finish 2025/05/06 07:22:23 ERROR : file1.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404) 2025/05/06 07:22:25 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:25 INFO : file2.txt: Copied (new) 2025/05/06 07:22:25 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:25 INFO : file4.txt: Copied (new) 2025/05/06 07:22:26 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:26 INFO : file3.txt: Copied (new) 2025/05/06 07:22:26 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:26 INFO : file5.txt: Copied (new) 2025/05/06 07:22:27 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:27 INFO : file7.txt: Copied (new) 2025/05/06 07:22:27 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:27 INFO : file6.txt: Copied (new) 2025/05/06 07:22:27 DEBUG : file9.txt: Uploading chunk 0, size=109, remain=0 2025/05/06 07:22:27 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:27 INFO : file8.txt: Copied (new) 2025/05/06 07:22:28 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:28 INFO : file9.txt: Copied (new) bisync_test.go:1943: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1943 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:458 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestBisyncRemoteRemote/max_delete_path1 Messages: setting up path1 2025/05/06 07:22:28 NOTICE: checking Path1 OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1' fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358 Error: Should be true Test: TestBisyncRemoteRemote/max_delete_path1 Messages: listing wrong, want file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109) got file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109) fstest.go:197: Not found "file1.txt" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358 Error: Not equal: expected: 0 actual : 1 Test: TestBisyncRemoteRemote/max_delete_path1 Messages: 1 objects not found 2025/05/06 07:22:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : file9.txt: Need to transfer - File not found at Destination 2025/05/06 07:22:37 DEBUG : OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2': Waiting for checks to finish 2025/05/06 07:22:37 DEBUG : OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2': Waiting for transfers to finish 2025/05/06 07:22:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:39 INFO : file2.txt: Copied (new) 2025/05/06 07:22:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:40 INFO : file1.txt: Copied (new) 2025/05/06 07:22:40 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:40 INFO : file3.txt: Copied (new) 2025/05/06 07:22:41 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:41 INFO : file5.txt: Copied (new) 2025/05/06 07:22:41 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:41 INFO : file4.txt: Copied (new) 2025/05/06 07:22:41 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:41 INFO : file6.txt: Copied (new) 2025/05/06 07:22:42 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:42 INFO : file7.txt: Copied (new) 2025/05/06 07:22:42 DEBUG : file9.txt: Uploading chunk 0, size=109, remain=0 2025/05/06 07:22:42 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/05/06 07:22:42 INFO : file8.txt: Copied (new) 2025/05/06 07:22:43 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/05/06 07:22:43 INFO : file9.txt: Copied (new) 2025/05/06 07:22:43 NOTICE: checking path2 OpenDrive root 'rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2' 2025/05/06 07:22:43 NOTICE: (01) : test max-delete-path1 2025/05/06 07:22:43 NOTICE: (02) : test initial bisync 2025/05/06 07:22:43 NOTICE: (03) : bisync resync 2025/05/06 07:22:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/06 07:22:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/05/06 07:22:48 INFO : Synching Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/" 2025/05/06 07:22:48 INFO : Copying Path2 files to Path1 2025/05/06 07:22:48 INFO : - Path2 Resync is copying files to - Path1 2025/05/06 07:22:48 INFO : file1.txt: Copied (server-side copy) 2025/05/06 07:22:48 INFO : - Path1 Resync is copying files to - Path2 2025/05/06 07:22:49 INFO : Resync updating listings 2025/05/06 07:22:49 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/" vs Path2 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/" 2025/05/06 07:22:49 INFO : Bisync successful 2025/05/06 07:22:49 NOTICE: (04) : test delete >50% of local files 2025/05/06 07:22:49 NOTICE: (05) : delete-file TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/file1.txt 2025/05/06 07:22:50 INFO : file1.txt: Deleted 2025/05/06 07:22:50 NOTICE: (06) : delete-file TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/file2.txt 2025/05/06 07:22:50 INFO : file2.txt: Deleted 2025/05/06 07:22:50 NOTICE: (07) : delete-file TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/file3.txt 2025/05/06 07:22:51 INFO : file3.txt: Deleted 2025/05/06 07:22:51 NOTICE: (08) : delete-file TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/file4.txt 2025/05/06 07:22:51 INFO : file4.txt: Deleted 2025/05/06 07:22:51 NOTICE: (09) : delete-file TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/file5.txt 2025/05/06 07:22:51 INFO : file5.txt: Deleted 2025/05/06 07:22:51 NOTICE: (10) : test sync should fail due to too many local deletes 2025/05/06 07:22:51 NOTICE: (11) : bisync 2025/05/06 07:22:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/06 07:22:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/05/06 07:22:55 INFO : Synching Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/" 2025/05/06 07:22:55 INFO : Building Path1 and Path2 listings 2025/05/06 07:22:56 INFO : Path1 checking for diffs 2025/05/06 07:22:56 INFO : - Path1 File was deleted - file1.txt 2025/05/06 07:22:56 INFO : - Path1 File was deleted - file2.txt 2025/05/06 07:22:56 INFO : - Path1 File was deleted - file3.txt 2025/05/06 07:22:56 INFO : - Path1 File was deleted - file4.txt 2025/05/06 07:22:56 INFO : - Path1 File was deleted - file5.txt 2025/05/06 07:22:56 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2025/05/06 07:22:56 INFO : Path2 checking for diffs 2025/05/06 07:22:56 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/". Run with --force if desired. 2025/05/06 07:22:56 NOTICE: Bisync aborted. Please try again. 2025/05/06 07:22:56 NOTICE: Bisync error: too many deletes 2025/05/06 07:22:56 NOTICE: (12) : copy-listings initial-fail 2025/05/06 07:22:56 NOTICE: (13) : test change max-delete limit to 60%. sync should run. 2025/05/06 07:22:56 NOTICE: (14) : bisync max-delete=60 2025/05/06 07:22:59 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/06 07:22:59 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/05/06 07:22:59 INFO : Synching Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/" 2025/05/06 07:22:59 INFO : Building Path1 and Path2 listings 2025/05/06 07:23:00 INFO : Path1 checking for diffs 2025/05/06 07:23:00 INFO : - Path1 File was deleted - file1.txt 2025/05/06 07:23:00 INFO : - Path1 File was deleted - file2.txt 2025/05/06 07:23:00 INFO : - Path1 File was deleted - file3.txt 2025/05/06 07:23:00 INFO : - Path1 File was deleted - file4.txt 2025/05/06 07:23:00 INFO : - Path1 File was deleted - file5.txt 2025/05/06 07:23:00 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2025/05/06 07:23:00 INFO : Path2 checking for diffs 2025/05/06 07:23:00 INFO : Applying changes 2025/05/06 07:23:00 INFO : - Path2 Queue delete - TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/file1.txt 2025/05/06 07:23:00 INFO : - Path2 Queue delete - TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/file2.txt 2025/05/06 07:23:00 INFO : - Path2 Queue delete - TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/file3.txt 2025/05/06 07:23:00 INFO : - Path2 Queue delete - TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/file4.txt 2025/05/06 07:23:00 INFO : - Path2 Queue delete - TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/file5.txt 2025/05/06 07:23:00 INFO : - Path1 Do queued copies to - Path2 2025/05/06 07:23:00 INFO : file2.txt: Deleted 2025/05/06 07:23:00 INFO : file3.txt: Deleted 2025/05/06 07:23:00 INFO : file4.txt: Deleted 2025/05/06 07:23:00 INFO : file1.txt: Deleted 2025/05/06 07:23:01 INFO : file5.txt: Deleted 2025/05/06 07:23:01 INFO : Updating listings 2025/05/06 07:23:01 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path1/" vs Path2 "TestOpenDrive:rclone-test-yefekal4wuxu/072216se/max_delete_path1/path2/" 2025/05/06 07:23:01 INFO : Bisync successful bisync_test.go:560: TEST max_delete_path1 PASSED 2025/05/06 07:23:02 DEBUG : OpenDrive root 'rclone-test-yefekal4wuxu': Purge remote --- FAIL: TestBisyncRemoteRemote (47.76s) --- FAIL: TestBisyncRemoteRemote/max_delete_path1 (46.42s) FAIL 2025/05/06 07:23:03 DEBUG : OpenDrive root 'rclone-test-ceheser9yote': Purge remote 2025/05/06 07:23:03 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncRemoteLocal$/^extended_filenames$|^TestBisyncRemoteRemote$/^max_delete_path1$'" - Finished ERROR in 1m7.817224489s (try 3/5): exit status 1: Failed [TestBisyncRemoteRemote/max_delete_path1]