"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteLocal$/^extended_filenames$|^TestBisyncRemoteRemote$/^(resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/09/03 02:16:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vunitiz6dike"
2025/09/03 02:16:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/03 02:16:08 DEBUG : Creating backend with remote "/tmp/rclone4032360458"
=== RUN   TestBisyncRemoteLocal
2025/09/03 02:16:08 DEBUG : Creating backend with remote "TestB2:rclone-test-rogupid5cequ"
2025/09/03 02:16:09 NOTICE: remote: TestB2:rclone-test-rogupid5cequ
=== RUN   TestBisyncRemoteLocal/extended_filenames
2025/09/03 02:16:09 DEBUG : Creating backend with remote "TestB2:rclone-test-rogupid5cequ/021609me"
2025/09/03 02:16:10 INFO  : path1: Making directory
2025/09/03 02:16:14 DEBUG : Creating backend with remote "TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1"
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/tmp/021609me"
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/tmp/021609me/extended_filenames/path2"
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/initial"
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/tmp/021609me/initdir/test_extended_filenames-beqezij4"
2025/09/03 02:16:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_: Making directory with metadata
2025/09/03 02:16:15 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:15 INFO  : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:15 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_
2025/09/03 02:16:15 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file1 - Copy.txt.5028a97.partial: renamed to: file1 - Copy.txt
2025/09/03 02:16:15 INFO  : file1 - Copy.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : Local file system at /tmp/021609me/initdir/test_extended_filenames-beqezij4: Waiting for checks to finish
2025/09/03 02:16:15 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:15 DEBUG : Local file system at /tmp/021609me/initdir/test_extended_filenames-beqezij4: Waiting for transfers to finish
2025/09/03 02:16:15 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:15 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:16:15 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:15 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:15 DEBUG : file1 - Copy (2).txt.5028a97.partial: renamed to: file1 - Copy (2).txt
2025/09/03 02:16:15 INFO  : file1 - Copy (2).txt: Copied (new)
2025/09/03 02:16:15 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:16:15 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:15 DEBUG : file_enconde_mañana_funcionará.txt.5028a97.partial: renamed to: file_enconde_mañana_funcionará.txt
2025/09/03 02:16:15 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:15 DEBUG : Русский.txt.5028a97.partial: renamed to: Русский.txt
2025/09/03 02:16:15 INFO  : Русский.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:15 DEBUG : filename_contains_ě_.txt.480f4fd7.partial: renamed to: filename_contains_ě_.txt
2025/09/03 02:16:15 INFO  : filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:15 DEBUG : filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: filename_contains_ࢺ_.txt
2025/09/03 02:16:15 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:15 INFO  : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt
2025/09/03 02:16:15 INFO  : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:15 INFO  : subdir_with_ࢺ_: Set directory modification time (using SetModTime)
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/modfiles"
2025/09/03 02:16:15 DEBUG : Creating backend with remote "/tmp/021609me/datadir/test_extended_filenames-dodirod1"
2025/09/03 02:16:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:15 DEBUG : Local file system at /tmp/021609me/datadir/test_extended_filenames-dodirod1: Waiting for checks to finish
2025/09/03 02:16:15 DEBUG : Local file system at /tmp/021609me/datadir/test_extended_filenames-dodirod1: Waiting for transfers to finish
2025/09/03 02:16:15 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:16:15 DEBUG : file2.txt: md5 = 40b811fb5009223b6da573f169619d8e OK
2025/09/03 02:16:15 DEBUG : file1.txt.f73bbb59.partial: renamed to: file1.txt
2025/09/03 02:16:15 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:15 DEBUG : file2.txt.761be064.partial: renamed to: file2.txt
2025/09/03 02:16:15 INFO  : file2.txt: Copied (new)
2025/09/03 02:16:15 INFO  : B2 bucket rclone-test-rogupid5cequ path 021609me/extended_filenames/path1: cleaning bucket "rclone-test-rogupid5cequ" of all files
2025/09/03 02:16:16 DEBUG : Waiting for deletions to finish
2025/09/03 02:16:16 ERROR : error listing: directory not found
2025/09/03 02:16:16 ERROR : Local file system at /tmp/021609me/extended_filenames/path2: Failed to list "": directory not found
2025/09/03 02:16:16 DEBUG : removing 1 level 0 directories
2025/09/03 02:16:16 INFO  : Local file system at /tmp/021609me/extended_filenames/path2: Removing directory
2025/09/03 02:16:16 ERROR : Failed to rmdir: stat /tmp/021609me/extended_filenames/path2: no such file or directory
2025/09/03 02:16:16 ERROR : error listing: directory not found
2025/09/03 02:16:16 NOTICE: checking initFs Local file system at /tmp/021609me/initdir/test_extended_filenames-beqezij4
2025/09/03 02:16:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 INFO  : subdir_with_ࢺ_: Making directory
2025/09/03 02:16:16 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:16 DEBUG : B2 bucket rclone-test-rogupid5cequ path 021609me/extended_filenames/path1: Waiting for checks to finish
2025/09/03 02:16:16 DEBUG : B2 bucket rclone-test-rogupid5cequ path 021609me/extended_filenames/path1: Waiting for transfers to finish
2025/09/03 02:16:17 DEBUG : file1 - Copy.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:17 INFO  : file1 - Copy.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : file_enconde_mañana_funcionará.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:17 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : file1 - Copy (2).txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:17 INFO  : file1 - Copy (2).txt: Copied (new)
2025/09/03 02:16:17 DEBUG : filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK
2025/09/03 02:16:17 INFO  : filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:17 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:17 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:17 DEBUG : filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK
2025/09/03 02:16:17 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : Русский.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:17 INFO  : Русский.txt: Copied (new)
2025/09/03 02:16:17 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK
2025/09/03 02:16:17 INFO  : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK
2025/09/03 02:16:18 INFO  : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:18 NOTICE: checking Path1 B2 bucket rclone-test-rogupid5cequ path 021609me/extended_filenames/path1
2025/09/03 02:16:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_: Making directory with metadata
2025/09/03 02:16:18 INFO  : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:18 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_
2025/09/03 02:16:18 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:18 DEBUG : file1 - Copy (2).txt.5028a97.partial: renamed to: file1 - Copy (2).txt
2025/09/03 02:16:18 INFO  : file1 - Copy (2).txt: Copied (new)
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:18 DEBUG : Local file system at /tmp/021609me/extended_filenames/path2: Waiting for checks to finish
2025/09/03 02:16:18 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:18 DEBUG : Local file system at /tmp/021609me/extended_filenames/path2: Waiting for transfers to finish
2025/09/03 02:16:18 DEBUG : file1 - Copy.txt.5028a97.partial: renamed to: file1 - Copy.txt
2025/09/03 02:16:18 INFO  : file1 - Copy.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:18 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:16:18 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:18 DEBUG : file_enconde_mañana_funcionará.txt.5028a97.partial: renamed to: file_enconde_mañana_funcionará.txt
2025/09/03 02:16:18 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:18 DEBUG : Русский.txt.5028a97.partial: renamed to: Русский.txt
2025/09/03 02:16:18 INFO  : Русский.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:18 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:16:18 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:18 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:18 DEBUG : filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: filename_contains_ࢺ_.txt
2025/09/03 02:16:18 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt
2025/09/03 02:16:18 INFO  : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:18 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.480f4fd7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt
2025/09/03 02:16:18 INFO  : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:18 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/09/03 02:16:18 DEBUG : filename_contains_ě_.txt.480f4fd7.partial: renamed to: filename_contains_ě_.txt
2025/09/03 02:16:18 INFO  : filename_contains_ě_.txt: Copied (new)
2025/09/03 02:16:18 INFO  : subdir_with_ࢺ_: Set directory modification time (using SetModTime)
2025/09/03 02:16:18 NOTICE: checking path2 Local file system at /tmp/021609me/extended_filenames/path2
2025/09/03 02:16:18 NOTICE: (01)  : test extended-filenames
2025/09/03 02:16:18 NOTICE: (02)  : fix-names TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/
2025/09/03 02:16:18 DEBUG : Creating backend with remote "TestB2:"
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/: is NFC: true
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/: is NFD: true
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/: is valid UTF8: true
2025/09/03 02:16:19 DEBUG : TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/: attempting to fix directory
2025/09/03 02:16:19 DEBUG : old and new are equal. Skipping. TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/ (e923e42d9822dbff9a5673bed7bcc3cf) TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/ (e923e42d9822dbff9a5673bed7bcc3cf)
2025/09/03 02:16:19 DEBUG : old and new are equal. Skipping. TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/ (e923e42d9822dbff9a5673bed7bcc3cf) TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/ (e923e42d9822dbff9a5673bed7bcc3cf)
2025/09/03 02:16:19 NOTICE: (03)  : fix-names /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/
2025/09/03 02:16:19 DEBUG : Creating backend with remote "/"
2025/09/03 02:16:19 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/: is NFC: true
2025/09/03 02:16:19 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/: is NFD: true
2025/09/03 02:16:19 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/: is valid UTF8: true
2025/09/03 02:16:19 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/: attempting to fix directory
2025/09/03 02:16:19 DEBUG : old and new are equal. Skipping. /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/ (2afff8a9fc5ec6e1c7f5d1dd2c7cae56) /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/ (2afff8a9fc5ec6e1c7f5d1dd2c7cae56)
2025/09/03 02:16:19 DEBUG : old and new are equal. Skipping. /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/ (2afff8a9fc5ec6e1c7f5d1dd2c7cae56) /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/ (2afff8a9fc5ec6e1c7f5d1dd2c7cae56)
2025/09/03 02:16:19 NOTICE: (04)  : fix-names TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: is NFC: true
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: is NFD: false
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: is valid UTF8: true
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: attempting to fix file -- filename hash: 35b54c7614f01a99539c6b3f1c231f93
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: true 091bda87a7fdc5e00246cdd19b92ca83
2025/09/03 02:16:19 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt equal: true
2025/09/03 02:16:19 DEBUG : HASH old: 091bda87a7fdc5e00246cdd19b92ca83 new: 091bda87a7fdc5e00246cdd19b92ca83 equal: true
2025/09/03 02:16:19 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: true 091bda87a7fdc5e00246cdd19b92ca83
2025/09/03 02:16:19 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt equal: true
2025/09/03 02:16:19 DEBUG : HASH old: 091bda87a7fdc5e00246cdd19b92ca83 new: 091bda87a7fdc5e00246cdd19b92ca83 equal: true
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: false 9dfdccff9dbb5d37e1739110e709078b
2025/09/03 02:16:20 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt equal: false
2025/09/03 02:16:20 DEBUG : HASH old: 9dfdccff9dbb5d37e1739110e709078b new: 091bda87a7fdc5e00246cdd19b92ca83 equal: false
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: false 9dfdccff9dbb5d37e1739110e709078b
2025/09/03 02:16:20 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt equal: false
2025/09/03 02:16:20 DEBUG : HASH old: 9dfdccff9dbb5d37e1739110e709078b new: 091bda87a7fdc5e00246cdd19b92ca83 equal: false
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: verified file exists at correct path. filename hash: 35b54c7614f01a99539c6b3f1c231f93
2025/09/03 02:16:20 NOTICE: (05)  : fix-names /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: is NFC: true
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: is NFD: false
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: is valid UTF8: true
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: attempting to fix file -- filename hash: 35b54c7614f01a99539c6b3f1c231f93
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: true 006511786ded183596369c0ed8094c97
2025/09/03 02:16:20 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt new: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt equal: true
2025/09/03 02:16:20 DEBUG : HASH old: 006511786ded183596369c0ed8094c97 new: 006511786ded183596369c0ed8094c97 equal: true
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: true 006511786ded183596369c0ed8094c97
2025/09/03 02:16:20 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt new: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt equal: true
2025/09/03 02:16:20 DEBUG : HASH old: 006511786ded183596369c0ed8094c97 new: 006511786ded183596369c0ed8094c97 equal: true
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: false 2346b86f07b4b1bb4807af98c9189c18
2025/09/03 02:16:20 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt new: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt equal: false
2025/09/03 02:16:20 DEBUG : HASH old: 2346b86f07b4b1bb4807af98c9189c18 new: 006511786ded183596369c0ed8094c97 equal: false
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: file exists: false 2346b86f07b4b1bb4807af98c9189c18
2025/09/03 02:16:20 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt new: /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt equal: false
2025/09/03 02:16:20 DEBUG : HASH old: 2346b86f07b4b1bb4807af98c9189c18 new: 006511786ded183596369c0ed8094c97 equal: false
2025/09/03 02:16:20 DEBUG : /tmp/021609me/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt: verified file exists at correct path. filename hash: 35b54c7614f01a99539c6b3f1c231f93
2025/09/03 02:16:20 NOTICE: (06)  : fix-names TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: is NFC: true
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: is NFD: false
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: is valid UTF8: true
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: attempting to fix file -- filename hash: 0e2b59d82407bceedb40a338be06ad7b
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: file exists: true 46f789fb2af26ca38cdd5906025ead6f
2025/09/03 02:16:20 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt equal: true
2025/09/03 02:16:20 DEBUG : HASH old: 46f789fb2af26ca38cdd5906025ead6f new: 46f789fb2af26ca38cdd5906025ead6f equal: true
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: file exists: true 46f789fb2af26ca38cdd5906025ead6f
2025/09/03 02:16:20 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt equal: true
2025/09/03 02:16:20 DEBUG : HASH old: 46f789fb2af26ca38cdd5906025ead6f new: 46f789fb2af26ca38cdd5906025ead6f equal: true
2025/09/03 02:16:20 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: file exists: false 6602566156c7c2ea81cab6c6c016db34
2025/09/03 02:16:20 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt equal: false
2025/09/03 02:16:20 DEBUG : HASH old: 6602566156c7c2ea81cab6c6c016db34 new: 46f789fb2af26ca38cdd5906025ead6f equal: false
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: file exists: false 6602566156c7c2ea81cab6c6c016db34
2025/09/03 02:16:21 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt equal: false
2025/09/03 02:16:21 DEBUG : HASH old: 6602566156c7c2ea81cab6c6c016db34 new: 46f789fb2af26ca38cdd5906025ead6f equal: false
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/Русский.txt: verified file exists at correct path. filename hash: 0e2b59d82407bceedb40a338be06ad7b
2025/09/03 02:16:21 NOTICE: (07)  : fix-names /tmp/021609me/extended_filenames/path2/Русский.txt
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: is NFC: true
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: is NFD: false
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: is valid UTF8: true
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: attempting to fix file -- filename hash: 0e2b59d82407bceedb40a338be06ad7b
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: file exists: true 5891d523db6dde1249663103ab410fb4
2025/09/03 02:16:21 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/Русский.txt new: /tmp/021609me/extended_filenames/path2/Русский.txt equal: true
2025/09/03 02:16:21 DEBUG : HASH old: 5891d523db6dde1249663103ab410fb4 new: 5891d523db6dde1249663103ab410fb4 equal: true
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: file exists: true 5891d523db6dde1249663103ab410fb4
2025/09/03 02:16:21 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/Русский.txt new: /tmp/021609me/extended_filenames/path2/Русский.txt equal: true
2025/09/03 02:16:21 DEBUG : HASH old: 5891d523db6dde1249663103ab410fb4 new: 5891d523db6dde1249663103ab410fb4 equal: true
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: file exists: false b7a17f6e67b75ca05f7ed6fc8a6ebdb1
2025/09/03 02:16:21 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/Русский.txt new: /tmp/021609me/extended_filenames/path2/Русский.txt equal: false
2025/09/03 02:16:21 DEBUG : HASH old: b7a17f6e67b75ca05f7ed6fc8a6ebdb1 new: 5891d523db6dde1249663103ab410fb4 equal: false
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: file exists: false b7a17f6e67b75ca05f7ed6fc8a6ebdb1
2025/09/03 02:16:21 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/Русский.txt new: /tmp/021609me/extended_filenames/path2/Русский.txt equal: false
2025/09/03 02:16:21 DEBUG : HASH old: b7a17f6e67b75ca05f7ed6fc8a6ebdb1 new: 5891d523db6dde1249663103ab410fb4 equal: false
2025/09/03 02:16:21 DEBUG : /tmp/021609me/extended_filenames/path2/Русский.txt: verified file exists at correct path. filename hash: 0e2b59d82407bceedb40a338be06ad7b
2025/09/03 02:16:21 NOTICE: (08)  : fix-names TestB2:rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: is NFC: true
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: is NFD: false
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: is valid UTF8: true
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: attempting to fix file -- filename hash: a179de4c245f1ac0afe5823062158ec4
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: file exists: true 39a3e67d3e71a9d6b567a2b90b4f5a74
2025/09/03 02:16:21 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt equal: true
2025/09/03 02:16:21 DEBUG : HASH old: 39a3e67d3e71a9d6b567a2b90b4f5a74 new: 39a3e67d3e71a9d6b567a2b90b4f5a74 equal: true
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: file exists: true 39a3e67d3e71a9d6b567a2b90b4f5a74
2025/09/03 02:16:21 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt equal: true
2025/09/03 02:16:21 DEBUG : HASH old: 39a3e67d3e71a9d6b567a2b90b4f5a74 new: 39a3e67d3e71a9d6b567a2b90b4f5a74 equal: true
2025/09/03 02:16:21 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: file exists: false d9476c84acbad68751349ea137dd40af
2025/09/03 02:16:21 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt equal: false
2025/09/03 02:16:21 DEBUG : HASH old: d9476c84acbad68751349ea137dd40af new: 39a3e67d3e71a9d6b567a2b90b4f5a74 equal: false
2025/09/03 02:16:22 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: file exists: false d9476c84acbad68751349ea137dd40af
2025/09/03 02:16:22 DEBUG : FILE old: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt new: rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt equal: false
2025/09/03 02:16:22 DEBUG : HASH old: d9476c84acbad68751349ea137dd40af new: 39a3e67d3e71a9d6b567a2b90b4f5a74 equal: false
2025/09/03 02:16:22 DEBUG : rclone-test-rogupid5cequ/021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: verified file exists at correct path. filename hash: a179de4c245f1ac0afe5823062158ec4
2025/09/03 02:16:22 NOTICE: (09)  : fix-names /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: is NFC: true
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: is NFD: false
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: is valid UTF8: true
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: attempting to fix file -- filename hash: a179de4c245f1ac0afe5823062158ec4
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: file exists: true 06ed2c06e8f6ca7f87149df10609bad7
2025/09/03 02:16:22 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt new: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt equal: true
2025/09/03 02:16:22 DEBUG : HASH old: 06ed2c06e8f6ca7f87149df10609bad7 new: 06ed2c06e8f6ca7f87149df10609bad7 equal: true
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: file exists: true 06ed2c06e8f6ca7f87149df10609bad7
2025/09/03 02:16:22 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt new: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt equal: true
2025/09/03 02:16:22 DEBUG : HASH old: 06ed2c06e8f6ca7f87149df10609bad7 new: 06ed2c06e8f6ca7f87149df10609bad7 equal: true
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: file exists: false 03b6833a4e14b4e1940e5ff99ece7d57
2025/09/03 02:16:22 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt new: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt equal: false
2025/09/03 02:16:22 DEBUG : HASH old: 03b6833a4e14b4e1940e5ff99ece7d57 new: 06ed2c06e8f6ca7f87149df10609bad7 equal: false
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: file exists: false 03b6833a4e14b4e1940e5ff99ece7d57
2025/09/03 02:16:22 DEBUG : FILE old: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt new: /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt equal: false
2025/09/03 02:16:22 DEBUG : HASH old: 03b6833a4e14b4e1940e5ff99ece7d57 new: 06ed2c06e8f6ca7f87149df10609bad7 equal: false
2025/09/03 02:16:22 DEBUG : /tmp/021609me/extended_filenames/path2/file_enconde_mañana_funcionará.txt: verified file exists at correct path. filename hash: a179de4c245f1ac0afe5823062158ec4
2025/09/03 02:16:22 NOTICE: (10)  : test initial bisync
2025/09/03 02:16:22 NOTICE: (11)  : bisync resync
2025/09/03 02:16:27 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:27 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/09/03 02:16:29 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:29 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/09/03 02:16:29 NOTICE: Local file system at /tmp/021609me/extended_filenames/path2: Replacing invalid UTF-8 characters in "ě\x81\xfe áñhࢺ_測試Рускийěáñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö"
2025/09/03 02:16:29 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:29 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧.5028a97.partial: renamed to: ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö
2025/09/03 02:16:29 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/09/03 02:16:29 NOTICE: Local file system at /tmp/021609me/extended_filenames/path2: Replacing invalid UTF-8 characters in "ě\x81\xfe áñhࢺ_測試Рускийěáñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö"
2025/09/03 02:16:29 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:29 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍.5028a97.partial: renamed to: ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö
2025/09/03 02:16:29 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
    bisync_test.go:1088: Fs is incapable of running test as the paths produce different results, skipping: extended_filenames (path1: 
        RCLONE_TESTRCLONE_TEST (BOTH) path2: 
        file1 - Copy (2).txtfile1 - Copy (2).txt (file1 - Copy (2).txtfile1 - Copy (2).txt))
         (fs1: B2 bucket rclone-test-rogupid5cequ path 021609me/extended_filenames/path1 fs2: Local file system at /tmp/021609me/extended_filenames/path2) 
        @@ -1 +1 @@
        -RCLONE_TESTRCLONE_TEST+file1 - Copy (2).txtfile1 - Copy (2).txt
2025/09/03 02:16:29 DEBUG : B2 bucket rclone-test-rogupid5cequ: Purge remote
2025/09/03 02:16:29 INFO  : B2 bucket rclone-test-rogupid5cequ: cleaning bucket "rclone-test-rogupid5cequ" of all files
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/RCLONE_TEST: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f107ef3a50e8fa6be_d20250903_m021617_c001_v0001182_t0058_u01756865777628")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f418e1fb304f084e0_d20250903_m021627_c001_v7007000_t0000_u01756865787419")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1104351f6a9472e2_d20250903_m021626_c001_v0001180_t0017_u01756865786436")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f418e1fb304f084e3_d20250903_m021627_c001_v7007000_t0000_u01756865787601")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f107ef3a50e8fa6f8_d20250903_m021627_c001_v0001182_t0044_u01756865787200")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/file1 - Copy (2).txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f104c686826b968b9_d20250903_m021617_c001_v0001154_t0016_u01756865777260")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/file1 - Copy.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1104351f6a9472a6_d20250903_m021617_c001_v0001180_t0050_u01756865777066")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/file1.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1094ce2295b93d1e_d20250903_m021617_c001_v0001099_t0004_u01756865777441")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/file_enconde_mañana_funcionará.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1104351f6a9472a8_d20250903_m021617_c001_v0001180_t0021_u01756865777349")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/filename_contains_ě_.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1104351f6a9472ac_d20250903_m021617_c001_v0001180_t0010_u01756865777557")
2025/09/03 02:16:30 DEBUG : 021609me/extended_filenames/path1/filename_contains_ࢺ_.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f104c686826b968bb_d20250903_m021617_c001_v0001154_t0021_u01756865777633")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/modtime_write_test: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f418e1fb304f084c8_d20250903_m021625_c001_v7007000_t0000_u01756865785883")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/modtime_write_test: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1092c3332e7612a0_d20250903_m021624_c001_v0001182_t0038_u01756865784683")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/modtime_write_test: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f104c686826b968c5_d20250903_m021622_c001_v0001154_t0009_u01756865782339")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1094ce2295b93d20_d20250903_m021617_c001_v0001099_t0006_u01756865777774")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f107ef3a50e8fa6c2_d20250903_m021617_c001_v0001182_t0044_u01756865777841")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f418e1fb304f08502_d20250903_m021629_c001_v7007000_t0000_u01756865789397")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1094ce2295b93d5e_d20250903_m021628_c001_v0001099_t0023_u01756865788157")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f418e1fb304f08506_d20250903_m021629_c001_v7007000_t0000_u01756865789581")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f104c686826b968d1_d20250903_m021629_c001_v0001154_t0002_u01756865789024")
2025/09/03 02:16:31 DEBUG : 021609me/extended_filenames/path1/Русский.txt: Deleting (id "4_z0f623d5350cd0bdb9f980e1b_f1104351f6a9472ae_d20250903_m021617_c001_v0001180_t0049_u01756865777766")
--- PASS: TestBisyncRemoteLocal (26.75s)
    --- SKIP: TestBisyncRemoteLocal/extended_filenames (20.51s)
=== RUN   TestBisyncLocalRemote
2025/09/03 02:16:35 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha"
2025/09/03 02:16:35 NOTICE: remote: TestB2:rclone-test-sapumut7meha
=== RUN   TestBisyncLocalRemote/check_access_filters
2025/09/03 02:16:35 DEBUG : Creating backend with remote "/tmp/021635be"
2025/09/03 02:16:35 DEBUG : Creating backend with remote "/tmp/021635be/check_access_filters/path1"
2025/09/03 02:16:35 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha/021635be"
2025/09/03 02:16:36 INFO  : path2: Making directory
2025/09/03 02:16:38 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2"
2025/09/03 02:16:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/09/03 02:16:39 DEBUG : Creating backend with remote "/tmp/021635be/initdir/test_check_access_filters-nofifeg3"
2025/09/03 02:16:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir-not: Making directory with metadata
2025/09/03 02:16:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/09/03 02:16:39 INFO  : file2.txt: Copied (new)
2025/09/03 02:16:39 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/09/03 02:16:39 DEBUG : subdir: Making directory with metadata
2025/09/03 02:16:39 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/09/03 02:16:39 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/09/03 02:16:39 INFO  : file3.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdirX: Making directory with metadata
2025/09/03 02:16:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/09/03 02:16:39 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:16:39 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:16:39 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/09/03 02:16:39 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/09/03 02:16:39 DEBUG : subdir/subdirA: Making directory with metadata
2025/09/03 02:16:39 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/09/03 02:16:39 DEBUG : subdir/subdirB: Making directory with metadata
2025/09/03 02:16:39 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/09/03 02:16:39 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/09/03 02:16:39 INFO  : file4.txt: Copied (new)
2025/09/03 02:16:39 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:39 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdirX/file20.txt.5028a97.partial: renamed to: subdirX/file20.txt
2025/09/03 02:16:39 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:39 INFO  : subdirX/file20.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : Local file system at /tmp/021635be/initdir/test_check_access_filters-nofifeg3: Waiting for checks to finish
2025/09/03 02:16:39 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : Local file system at /tmp/021635be/initdir/test_check_access_filters-nofifeg3: Waiting for transfers to finish
2025/09/03 02:16:39 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/09/03 02:16:39 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir-not/file20.txt.5028a97.partial: renamed to: subdir-not/file20.txt
2025/09/03 02:16:39 INFO  : subdir-not/file20.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdirX/subdirX1/file30.txt.5028a97.partial: renamed to: subdirX/subdirX1/file30.txt
2025/09/03 02:16:39 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/subdirB/file30.txt.5028a97.partial: renamed to: subdir/subdirB/file30.txt
2025/09/03 02:16:39 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdir/subdirB/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/file30.txt.5028a97.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/09/03 02:16:39 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/subdirA/file30.txt.5028a97.partial: renamed to: subdir/subdirA/file30.txt
2025/09/03 02:16:39 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:39 DEBUG : subdir/subdirA/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/09/03 02:16:39 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/09/03 02:16:39 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:16:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/09/03 02:16:39 DEBUG : Creating backend with remote "/tmp/021635be/datadir/test_check_access_filters-niqujeg6"
2025/09/03 02:16:39 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:39 DEBUG : Local file system at /tmp/021635be/datadir/test_check_access_filters-niqujeg6: Waiting for checks to finish
2025/09/03 02:16:39 DEBUG : Local file system at /tmp/021635be/datadir/test_check_access_filters-niqujeg6: Waiting for transfers to finish
2025/09/03 02:16:39 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/09/03 02:16:39 DEBUG : exclude-other-filtersfile.txt.9180e6d6.partial: renamed to: exclude-other-filtersfile.txt
2025/09/03 02:16:39 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/09/03 02:16:39 DEBUG : include-other-filtersfile.txt.5fb1cd78.partial: renamed to: include-other-filtersfile.txt
2025/09/03 02:16:39 INFO  : include-other-filtersfile.txt: Copied (new)
2025/09/03 02:16:39 DEBUG : Waiting for deletions to finish
2025/09/03 02:16:39 ERROR : error listing: directory not found
2025/09/03 02:16:39 ERROR : Local file system at /tmp/021635be/check_access_filters/path1: Failed to list "": directory not found
2025/09/03 02:16:39 DEBUG : removing 1 level 0 directories
2025/09/03 02:16:39 INFO  : Local file system at /tmp/021635be/check_access_filters/path1: Removing directory
2025/09/03 02:16:39 ERROR : Failed to rmdir: stat /tmp/021635be/check_access_filters/path1: no such file or directory
2025/09/03 02:16:39 INFO  : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: cleaning bucket "rclone-test-sapumut7meha" of all files
2025/09/03 02:16:40 ERROR : error listing: directory not found
2025/09/03 02:16:40 NOTICE: checking initFs Local file system at /tmp/021635be/initdir/test_check_access_filters-nofifeg3
2025/09/03 02:16:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not: Making directory with metadata
2025/09/03 02:16:40 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/09/03 02:16:40 DEBUG : subdir: Making directory with metadata
2025/09/03 02:16:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/09/03 02:16:40 DEBUG : subdirX: Making directory with metadata
2025/09/03 02:16:40 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:16:40 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:40 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/09/03 02:16:40 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/09/03 02:16:40 INFO  : file2.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:16:40 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/09/03 02:16:40 INFO  : file3.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/09/03 02:16:40 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/09/03 02:16:40 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/09/03 02:16:40 INFO  : file4.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir/subdirA: Making directory with metadata
2025/09/03 02:16:40 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/09/03 02:16:40 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/09/03 02:16:40 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir/subdirB: Making directory with metadata
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/file20.txt.5028a97.partial: renamed to: subdirX/file20.txt
2025/09/03 02:16:40 INFO  : subdirX/file20.txt: Copied (new)
2025/09/03 02:16:40 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/09/03 02:16:40 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:16:40 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/09/03 02:16:40 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/file20.txt.5028a97.partial: renamed to: subdir-not/file20.txt
2025/09/03 02:16:40 INFO  : subdir-not/file20.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : Local file system at /tmp/021635be/check_access_filters/path1: Waiting for checks to finish
2025/09/03 02:16:40 DEBUG : Local file system at /tmp/021635be/check_access_filters/path1: Waiting for transfers to finish
2025/09/03 02:16:40 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/file30.txt.5028a97.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/09/03 02:16:40 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir/subdirB/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/09/03 02:16:40 DEBUG : subdir/subdirA/file30.txt.5028a97.partial: renamed to: subdir/subdirA/file30.txt
2025/09/03 02:16:40 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdir/subdirA/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:16:40 DEBUG : subdir/subdirB/file30.txt.5028a97.partial: renamed to: subdir/subdirB/file30.txt
2025/09/03 02:16:40 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/file30.txt.5028a97.partial: renamed to: subdirX/subdirX1/file30.txt
2025/09/03 02:16:40 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/09/03 02:16:40 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/09/03 02:16:40 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:16:40 NOTICE: checking Path1 Local file system at /tmp/021635be/check_access_filters/path1
2025/09/03 02:16:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 INFO  : subdir-not: Making directory
2025/09/03 02:16:40 INFO  : subdir: Making directory
2025/09/03 02:16:40 INFO  : subdirX: Making directory
2025/09/03 02:16:40 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 INFO  : subdirX/subdirX1: Making directory
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 INFO  : subdir-not/subdir-not2: Making directory
2025/09/03 02:16:40 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 INFO  : subdir/subdirA: Making directory
2025/09/03 02:16:40 INFO  : subdir/subdirB: Making directory
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:40 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Waiting for checks to finish
2025/09/03 02:16:40 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Waiting for transfers to finish
2025/09/03 02:16:41 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:41 INFO  : file2.txt: Copied (new)
2025/09/03 02:16:41 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:41 INFO  : file1.txt: Copied (new)
2025/09/03 02:16:41 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:41 INFO  : file4.txt: Copied (new)
2025/09/03 02:16:41 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:41 INFO  : file3.txt: Copied (new)
2025/09/03 02:16:41 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:41 INFO  : subdirX/file20.txt: Copied (new)
2025/09/03 02:16:42 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:42 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/09/03 02:16:42 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:42 INFO  : subdir-not/file20.txt: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:42 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:16:42 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:42 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/09/03 02:16:42 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:42 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/09/03 02:16:43 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:16:43 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/09/03 02:16:43 NOTICE: checking path2 B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2
2025/09/03 02:16:43 NOTICE: (01)  : test check-access-filters
2025/09/03 02:16:43 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/09/03 02:16:43 NOTICE: (03)  : copy-file /tmp/021635be/datadir/test_check_access_filters-niqujeg6/exclude-other-filtersfile.txt /tmp/021635be/workdir/
2025/09/03 02:16:43 DEBUG : copyFile "/tmp/021635be/datadir/test_check_access_filters-niqujeg6/exclude-other-filtersfile.txt" to "/tmp/021635be/workdir/" as ""
2025/09/03 02:16:43 DEBUG : Creating backend with remote "/tmp/021635be/datadir/test_check_access_filters-niqujeg6/exclude-other-filtersfile.txt"
2025/09/03 02:16:43 DEBUG : Creating backend with remote "/tmp/021635be/workdir/"
2025/09/03 02:16:43 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/021635be/workdir" as "exclude-other-filtersfile.txt"
2025/09/03 02:16:43 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/09/03 02:16:43 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/09/03 02:16:43 DEBUG : exclude-other-filtersfile.txt.9180e6d6.partial: renamed to: exclude-other-filtersfile.txt
2025/09/03 02:16:43 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/09/03 02:16:43 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/09/03 02:16:43 NOTICE: (05)  : bisync resync filters-file=/tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:16:46 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:16:46 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:16:46 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:16:46 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/09/03 02:16:46 INFO  : Synching Path1 "/tmp/021635be/check_access_filters/path1/" with Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:16:46 INFO  : Using filters file /tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:16:46 INFO  : Storing filters file hash to /tmp/021635be/workdir/exclude-other-filtersfile.txt.md5
2025/09/03 02:16:46 INFO  : Copying Path2 files to Path1
2025/09/03 02:16:46 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:16:47 INFO  : There was nothing to transfer
2025/09/03 02:16:47 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:16:48 INFO  : There was nothing to transfer
2025/09/03 02:16:48 INFO  : Resync updating listings
2025/09/03 02:16:48 INFO  : Validating listings for Path1 "/tmp/021635be/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:16:48 INFO  : Bisync successful
2025/09/03 02:16:48 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/09/03 02:16:48 NOTICE: (07)  : bisync check-access filters-file=/tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:16:51 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:16:51 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:16:51 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:16:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/09/03 02:16:51 INFO  : Synching Path1 "/tmp/021635be/check_access_filters/path1/" with Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:16:51 INFO  : Using filters file /tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:16:51 INFO  : Building Path1 and Path2 listings
2025/09/03 02:16:52 INFO  : Path1 checking for diffs
2025/09/03 02:16:52 INFO  : Path2 checking for diffs
2025/09/03 02:16:52 INFO  : Checking access health
2025/09/03 02:16:52 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/09/03 02:16:52 INFO  : No changes found
2025/09/03 02:16:52 INFO  : Updating listings
2025/09/03 02:16:52 INFO  : Validating listings for Path1 "/tmp/021635be/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:16:52 INFO  : Bisync successful
2025/09/03 02:16:52 NOTICE: (08)  : copy-listings exclude-initial
2025/09/03 02:16:52 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2025/09/03 02:16:52 NOTICE: (10)  : delete-file TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/09/03 02:16:52 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir/subdirA/"
2025/09/03 02:16:53 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir/subdirA/" to be canonical "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir/subdirA"
2025/09/03 02:16:54 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:16:54 NOTICE: (11)  : delete-file /tmp/021635be/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/09/03 02:16:54 DEBUG : Creating backend with remote "/tmp/021635be/check_access_filters/path1/subdir-not/"
2025/09/03 02:16:54 DEBUG : fs cache: renaming cache item "/tmp/021635be/check_access_filters/path1/subdir-not/" to be canonical "/tmp/021635be/check_access_filters/path1/subdir-not"
2025/09/03 02:16:54 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:16:54 NOTICE: (12)  : delete-file TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/09/03 02:16:54 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir-not/subdir-not2/"
2025/09/03 02:16:55 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/subdir-not/subdir-not2"
2025/09/03 02:16:56 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:16:56 NOTICE: (13)  : delete-file /tmp/021635be/check_access_filters/path1/subdirX/RCLONE_TEST
2025/09/03 02:16:56 DEBUG : Creating backend with remote "/tmp/021635be/check_access_filters/path1/subdirX/"
2025/09/03 02:16:56 DEBUG : fs cache: renaming cache item "/tmp/021635be/check_access_filters/path1/subdirX/" to be canonical "/tmp/021635be/check_access_filters/path1/subdirX"
2025/09/03 02:16:56 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:16:56 NOTICE: (14)  : test EXCLUDE - test should PASS
2025/09/03 02:16:56 NOTICE: (15)  : bisync check-access filters-file=/tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:17:00 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:17:00 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:17:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/09/03 02:17:00 INFO  : Synching Path1 "/tmp/021635be/check_access_filters/path1/" with Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:17:00 INFO  : Using filters file /tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:17:00 INFO  : Building Path1 and Path2 listings
2025/09/03 02:17:01 INFO  : Path1 checking for diffs
2025/09/03 02:17:01 INFO  : Path2 checking for diffs
2025/09/03 02:17:01 INFO  : Checking access health
2025/09/03 02:17:01 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/09/03 02:17:01 INFO  : No changes found
2025/09/03 02:17:01 INFO  : Updating listings
2025/09/03 02:17:01 INFO  : Validating listings for Path1 "/tmp/021635be/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:17:01 INFO  : Bisync successful
2025/09/03 02:17:01 NOTICE: (16)  : copy-listings exclude-pass-run
2025/09/03 02:17:01 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2025/09/03 02:17:01 NOTICE: (18)  : delete-file TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/RCLONE_TEST
2025/09/03 02:17:01 DEBUG : Creating backend with remote "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:17:02 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/" to be canonical "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2"
2025/09/03 02:17:02 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:17:02 NOTICE: (19)  : delete-file /tmp/021635be/check_access_filters/path1/subdir/RCLONE_TEST
2025/09/03 02:17:02 DEBUG : Creating backend with remote "/tmp/021635be/check_access_filters/path1/subdir/"
2025/09/03 02:17:02 DEBUG : fs cache: renaming cache item "/tmp/021635be/check_access_filters/path1/subdir/" to be canonical "/tmp/021635be/check_access_filters/path1/subdir"
2025/09/03 02:17:02 INFO  : RCLONE_TEST: Deleted
2025/09/03 02:17:02 NOTICE: (20)  : test EXCLUDE - test should ABORT
2025/09/03 02:17:02 NOTICE: (21)  : bisync check-access filters-file=/tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:17:06 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:17:06 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:17:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/09/03 02:17:06 INFO  : Synching Path1 "/tmp/021635be/check_access_filters/path1/" with Path2 "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/"
2025/09/03 02:17:06 INFO  : Using filters file /tmp/021635be/workdir/exclude-other-filtersfile.txt
2025/09/03 02:17:06 INFO  : Building Path1 and Path2 listings
2025/09/03 02:17:07 INFO  : Path1 checking for diffs
2025/09/03 02:17:07 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/09/03 02:17:07 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/09/03 02:17:07 INFO  : Path2 checking for diffs
2025/09/03 02:17:07 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/09/03 02:17:07 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/09/03 02:17:07 INFO  : Checking access health
2025/09/03 02:17:07 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/09/03 02:17:07 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/09/03 02:17:07 ERROR : Bisync critical error: check file check failed
2025/09/03 02:17:07 ERROR : Bisync aborted. Must run --resync to recover.
2025/09/03 02:17:07 NOTICE: Bisync error: bisync aborted
2025/09/03 02:17:07 NOTICE: (22)  : move-listings exclude-error-run
2025/09/03 02:17:07 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2025/09/03 02:17:07 NOTICE: (24)  : test reset to the initial state
2025/09/03 02:17:07 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial /tmp/021635be/check_access_filters/path1/
2025/09/03 02:17:07 DEBUG : Creating backend with remote "/tmp/021635be/check_access_filters/path1/"
2025/09/03 02:17:07 DEBUG : fs cache: renaming cache item "/tmp/021635be/check_access_filters/path1/" to be canonical "/tmp/021635be/check_access_filters/path1"
2025/09/03 02:17:07 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/09/03 02:17:07 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/09/03 02:17:07 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/09/03 02:17:07 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : file2.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : file4.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : file3.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:07 DEBUG : file1.txt: Unchanged skipping
2025/09/03 02:17:07 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/09/03 02:17:07 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:07 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/09/03 02:17:07 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/09/03 02:17:07 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/09/03 02:17:07 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir/file20.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:07 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/09/03 02:17:07 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:17:07 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/09/03 02:17:07 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/09/03 02:17:07 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/09/03 02:17:07 DEBUG : Local file system at /tmp/021635be/check_access_filters/path1: Waiting for checks to finish
2025/09/03 02:17:07 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:17:07 DEBUG : Local file system at /tmp/021635be/check_access_filters/path1: Waiting for transfers to finish
2025/09/03 02:17:07 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/09/03 02:17:07 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:17:07 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/09/03 02:17:07 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/09/03 02:17:07 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/09/03 02:17:07 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:17:07 NOTICE: (26)  : sync-dir /tmp/021635be/check_access_filters/path1/ TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/
2025/09/03 02:17:07 DEBUG : fs cache: switching user supplied name "/tmp/021635be/check_access_filters/path1/" for canonical name "/tmp/021635be/check_access_filters/path1"
2025/09/03 02:17:07 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2/" for canonical name "TestB2:rclone-test-sapumut7meha/021635be/check_access_filters/path2"
2025/09/03 02:17:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:07 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : file1.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : file3.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : file2.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : file4.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir/file20.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:07 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/09/03 02:17:08 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:08 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:08 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/09/03 02:17:08 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:08 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/03 02:17:08 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/09/03 02:17:08 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Waiting for checks to finish
2025/09/03 02:17:08 DEBUG : B2 bucket rclone-test-sapumut7meha path 021635be/check_access_filters/path2: Waiting for transfers to finish
2025/09/03 02:17:08 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:17:08 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:17:08 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:17:08 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/09/03 02:17:08 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:17:08 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/09/03 02:17:08 DEBUG : Waiting for deletions to finish
2025/09/03 02:17:08 NOTICE: (27)  : copy-file /tmp/021635be/datadir/test_check_access_filters-niqujeg6/include-other-filtersfile.txt /tmp/021635be/workdir/
2025/09/03 02:17:08 DEBUG : copyFile "/tmp/021635be/datadir/test_check_access_filters-niqujeg6/include-other-filtersfile.txt" to "/tmp/021635be/workdir/" as ""
2025/09/03 02:17:08 DEBUG : Creating backend with remote "/tmp/021635be/datadir/test_check_access_filters-niqujeg6/include-other-filtersfile.txt"
2025/09/03 02:17:08 DEBUG : Creating backend with remote "/tmp/021635be/workdir/"
2025/09/03 02:17:08 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/021635be/workdir" as "include-other-filtersfile.txt"
2025/09/03 02:17:08 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:08 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/09/03 02:17:08 DEBUG : include-other-filtersfile.txt.5fb1cd78.partial: renamed to: include-other-filtersfile.txt
2025/09/03 02:17:08 INFO  : include-other-filtersfile.txt: Copied (new)
2025/09/03 02:17:08 NOTICE: (28)  : bisync resync filters-file=/tmp/021635be/workdir/include-other-filtersfile.txt
2025/09/03 02:17:08 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1131-15.backblaze.com/b2api/v1/b2_upload_file/6f729dd350cd0bdb9f980e1b/c001_v0001131_t0039": EOF
2025/09/03 02:17:08 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1131-15.backblaze.com/b2api/v1/b2_upload_file/6f729dd350cd0bdb9f980e1b/c001_v0001131_t0039": EOF)
2025/09/03 02:17:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    bisync_test.go:1024: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1024
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1040
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1105
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	Post "https://pod-000-1131-15.backblaze.com/b2api/v1/b2_upload_file/6f729dd350cd0bdb9f980e1b/c001_v0001131_t0039": EOF
        	Test:       	TestBisyncLocalRemote/check_access_filters
2025/09/03 02:17:08 DEBUG : B2 bucket rclone-test-sapumut7meha: Purge remote
2025/09/03 02:17:08 INFO  : B2 bucket rclone-test-sapumut7meha: cleaning bucket "rclone-test-sapumut7meha" of all files
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f112b0800c70400c4_d20250903_m021707_c001_v0001131_t0025_u01756865827836")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f400d106f3436f489_d20250903_m021702_c001_v7007000_t0000_u01756865822748")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f112b0800c703ffe0_d20250903_m021641_c001_v0001131_t0032_u01756865801819")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/file1.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f115ae2d00360b24f_d20250903_m021641_c001_v0001131_t0050_u01756865801264")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/file2.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d23_d20250903_m021641_c001_v0001183_t0021_u01756865801441")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/file3.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce5295b_d20250903_m021641_c001_v0001171_t0057_u01756865801630")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/file4.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d25_d20250903_m021641_c001_v0001183_t0045_u01756865801635")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f400d106f3436f48d_d20250903_m021706_c001_v7007000_t0000_u01756865826502")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1018c918afe0279d_d20250903_m021705_c001_v0001092_t0036_u01756865825125")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f115ae2d00360b319_d20250903_m021703_c001_v0001131_t0039_u01756865823291")
2025/09/03 02:17:09 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f400d106f3436f485_d20250903_m021700_c001_v7007000_t0000_u01756865820625")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f100259020e3562db_d20250903_m021659_c001_v0001099_t0021_u01756865819295")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce529bb_d20250903_m021656_c001_v0001171_t0037_u01756865816925")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f421ff5b6efa192ce_d20250903_m021651_c001_v7007000_t0000_u01756865811595")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f10801672b0425152_d20250903_m021649_c001_v0001179_t0048_u01756865809871")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d51_d20250903_m021648_c001_v0001183_t0021_u01756865808466")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f421ff5b6efa192c5_d20250903_m021646_c001_v7007000_t0000_u01756865806821")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f118c53da405ab586_d20250903_m021645_c001_v0001172_t0042_u01756865805481")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/modtime_write_test: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f112b0800c703fff4_d20250903_m021643_c001_v0001131_t0033_u01756865803602")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f112b0800c703ffe2_d20250903_m021642_c001_v0001131_t0041_u01756865802129")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/file20.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f115ae2d00360b25d_d20250903_m021642_c001_v0001131_t0049_u01756865802177")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce52a03_d20250903_m021708_c001_v0001171_t0028_u01756865828536")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f4155e626cd547458_d20250903_m021656_c001_v7007000_t0000_u01756865816371")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d33_d20250903_m021642_c001_v0001183_t0046_u01756865802454")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir-not/subdir-not2/file30.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f115ae2d00360b261_d20250903_m021642_c001_v0001131_t0031_u01756865802598")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d31_d20250903_m021642_c001_v0001183_t0050_u01756865802254")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir/file20.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce52967_d20250903_m021642_c001_v0001171_t0015_u01756865802383")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43dc1_d20250903_m021708_c001_v0001183_t0012_u01756865828526")
2025/09/03 02:17:10 DEBUG : 021635be/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f40782267408c27ac_d20250903_m021654_c001_v7007000_t0000_u01756865814253")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d35_d20250903_m021642_c001_v0001183_t0010_u01756865802658")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdir/subdirA/file30.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d3b_d20250903_m021642_c001_v0001183_t0038_u01756865802865")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdir/subdirB/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f112b0800c703ffea_d20250903_m021642_c001_v0001131_t0038_u01756865802609")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdir/subdirB/file30.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce52969_d20250903_m021642_c001_v0001171_t0028_u01756865802668")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdirX/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f115ae2d00360b255_d20250903_m021641_c001_v0001131_t0054_u01756865801670")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdirX/file20.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d2d_d20250903_m021641_c001_v0001183_t0016_u01756865801851")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f11805dff5ce52961_d20250903_m021641_c001_v0001171_t0048_u01756865801932")
2025/09/03 02:17:11 DEBUG : 021635be/check_access_filters/path2/subdirX/subdirX1/file30.txt: Deleting (id "4_z6f729dd350cd0bdb9f980e1b_f1075c0d2dde43d2f_d20250903_m021642_c001_v0001183_t0008_u01756865802044")
--- FAIL: TestBisyncLocalRemote (40.00s)
    --- FAIL: TestBisyncLocalRemote/check_access_filters (32.94s)
=== RUN   TestBisyncRemoteRemote
2025/09/03 02:17:15 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu"
2025/09/03 02:17:15 NOTICE: remote: TestB2:rclone-test-pulobiq8pitu
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/09/03 02:17:15 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi"
2025/09/03 02:17:16 INFO  : path1: Making directory
2025/09/03 02:17:18 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1"
2025/09/03 02:17:19 INFO  : path2: Making directory
2025/09/03 02:17:19 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2"
2025/09/03 02:17:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/09/03 02:17:20 DEBUG : Creating backend with remote "/tmp/021715xi/initdir/test_resync_modes-tinuxaz7"
2025/09/03 02:17:20 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : Local file system at /tmp/021715xi/initdir/test_resync_modes-tinuxaz7: Waiting for checks to finish
2025/09/03 02:17:20 DEBUG : Local file system at /tmp/021715xi/initdir/test_resync_modes-tinuxaz7: Waiting for transfers to finish
2025/09/03 02:17:20 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:17:20 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:17:20 INFO  : file1.txt: Copied (new)
2025/09/03 02:17:20 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:17:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:17:20 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/09/03 02:17:20 INFO  : file2.txt: Copied (new)
2025/09/03 02:17:20 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:17:20 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:17:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/09/03 02:17:20 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:20 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:20 DEBUG : Local file system at /tmp/021715xi/datadir/test_resync_modes-selonex3: Waiting for checks to finish
2025/09/03 02:17:20 DEBUG : Local file system at /tmp/021715xi/datadir/test_resync_modes-selonex3: Waiting for transfers to finish
2025/09/03 02:17:20 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/09/03 02:17:20 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2025/09/03 02:17:20 INFO  : file1L.txt: Copied (new)
2025/09/03 02:17:20 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/09/03 02:17:20 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/09/03 02:17:20 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2025/09/03 02:17:20 INFO  : file2.txt: Copied (new)
2025/09/03 02:17:20 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2025/09/03 02:17:20 INFO  : file1R.txt: Copied (new)
2025/09/03 02:17:20 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:17:21 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:17:22 NOTICE: checking initFs Local file system at /tmp/021715xi/initdir/test_resync_modes-tinuxaz7
2025/09/03 02:17:22 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:22 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:22 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:22 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Waiting for checks to finish
2025/09/03 02:17:22 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Waiting for transfers to finish
2025/09/03 02:17:23 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:17:23 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:17:23 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:17:23 INFO  : file1.txt: Copied (new)
2025/09/03 02:17:23 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:17:23 INFO  : file2.txt: Copied (new)
2025/09/03 02:17:23 NOTICE: checking Path1 B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1
2025/09/03 02:17:24 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:17:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:17:24 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Waiting for checks to finish
2025/09/03 02:17:24 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Waiting for transfers to finish
2025/09/03 02:17:24 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:17:24 INFO  : file1.txt: Copied (new)
2025/09/03 02:17:25 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:17:25 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:17:25 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:17:25 INFO  : file2.txt: Copied (new)
2025/09/03 02:17:25 NOTICE: checking path2 B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2
2025/09/03 02:17:25 NOTICE: (01)  : test resync-mode
2025/09/03 02:17:25 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:17:25 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:17:25 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/"
2025/09/03 02:17:25 DEBUG : fs cache: renaming cache item "/tmp/021715xi/datadir/test_resync_modes-selonex3/" to be canonical "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:25 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/09/03 02:17:25 NOTICE: (04)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:17:25 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:17:25 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:17:25 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:26 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:17:26 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/09/03 02:17:28 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:17:28 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:17:28 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:17:28 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:28 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/09/03 02:17:28 NOTICE: (06)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:17:28 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:17:28 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:17:28 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:17:29 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:17:29 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/09/03 02:17:31 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:17:31 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:17:31 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/09/03 02:17:31 NOTICE: (08)  : bisync resync resync-mode=newer
2025/09/03 02:17:39 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:39 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:17:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:17:39 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:39 INFO  : Copying Path2 files to Path1
2025/09/03 02:17:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:17:39 INFO  : There was nothing to transfer
2025/09/03 02:17:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:17:40 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:17:40 INFO  : Resync updating listings
2025/09/03 02:17:40 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:40 INFO  : Bisync successful
2025/09/03 02:17:40 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:17:40 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:17:40 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:40 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/09/03 02:17:40 NOTICE: (11)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:17:40 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:17:40 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:17:40 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:41 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:17:41 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/09/03 02:17:43 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:17:43 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:17:43 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:17:43 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:43 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/09/03 02:17:43 NOTICE: (13)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:17:43 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:17:43 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:17:43 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:17:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:17:44 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/09/03 02:17:44 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:17:46 INFO  : file1L.txt: Updated modification time in destination
2025/09/03 02:17:46 DEBUG : file1L.txt: Unchanged skipping
2025/09/03 02:17:46 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/09/03 02:17:46 NOTICE: (15)  : bisync resync resync-mode=path2
2025/09/03 02:17:54 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:54 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:17:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:17:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:17:54 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:54 INFO  : Copying Path2 files to Path1
2025/09/03 02:17:54 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:17:55 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:17:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:17:55 INFO  : Resync updating listings
2025/09/03 02:17:55 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:55 INFO  : Bisync successful
2025/09/03 02:17:55 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:17:55 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:17:55 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:55 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/09/03 02:17:55 NOTICE: (18)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:17:55 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:17:55 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:17:55 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:17:56 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:17:56 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/09/03 02:17:56 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:17:58 INFO  : file1R.txt: Updated modification time in destination
2025/09/03 02:17:58 DEBUG : file1R.txt: Unchanged skipping
2025/09/03 02:17:58 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:17:58 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:17:58 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/09/03 02:17:58 NOTICE: (20)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:17:58 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:17:58 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:17:58 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:17:59 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:17:59 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/09/03 02:18:00 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:18:00 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:18:00 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/09/03 02:18:00 NOTICE: (22)  : bisync resync resync-mode=larger
2025/09/03 02:18:09 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:09 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:18:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:18:09 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:09 INFO  : Copying Path2 files to Path1
2025/09/03 02:18:09 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:18:09 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/09/03 02:18:10 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:18:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:18:10 INFO  : Resync updating listings
2025/09/03 02:18:10 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:10 INFO  : Bisync successful
2025/09/03 02:18:10 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:18:10 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:18:10 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:10 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/09/03 02:18:10 NOTICE: (25)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:18:10 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:18:10 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:18:10 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:11 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:18:11 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/09/03 02:18:11 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:18:12 INFO  : file1R.txt: Updated modification time in destination
2025/09/03 02:18:12 DEBUG : file1R.txt: Unchanged skipping
2025/09/03 02:18:12 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:18:12 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:12 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/09/03 02:18:12 NOTICE: (27)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:18:12 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:18:12 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:18:12 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:18:13 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:18:14 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/09/03 02:18:15 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:18:15 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:18:15 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/09/03 02:18:15 NOTICE: (29)  : bisync resync resync-mode=older
2025/09/03 02:18:23 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:23 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:23 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:18:23 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:18:23 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:23 INFO  : Copying Path2 files to Path1
2025/09/03 02:18:23 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:18:23 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/09/03 02:18:24 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:18:24 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:18:24 INFO  : Resync updating listings
2025/09/03 02:18:24 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:24 INFO  : Bisync successful
2025/09/03 02:18:24 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:18:24 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:18:24 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:24 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/09/03 02:18:24 NOTICE: (32)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:18:24 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:18:24 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:18:24 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:25 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:18:25 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/09/03 02:18:25 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:18:26 INFO  : file1R.txt: Updated modification time in destination
2025/09/03 02:18:26 DEBUG : file1R.txt: Unchanged skipping
2025/09/03 02:18:26 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:18:26 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:26 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/09/03 02:18:26 NOTICE: (34)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:18:26 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:18:26 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:18:26 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:18:27 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:18:28 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/09/03 02:18:29 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:18:29 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:18:29 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/09/03 02:18:29 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/09/03 02:18:37 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:37 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:37 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:18:37 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:18:37 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:37 INFO  : Copying Path2 files to Path1
2025/09/03 02:18:37 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:18:38 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/09/03 02:18:38 INFO  : There was nothing to transfer
2025/09/03 02:18:38 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:18:38 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/09/03 02:18:38 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:18:38 INFO  : Resync updating listings
2025/09/03 02:18:38 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:38 INFO  : Bisync successful
2025/09/03 02:18:38 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/09/03 02:18:38 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:18:38 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:38 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/09/03 02:18:38 NOTICE: (39)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:18:38 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:18:38 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:18:38 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:39 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:18:39 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/09/03 02:18:41 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:18:41 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:18:41 NOTICE: (40)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file2.txt
2025/09/03 02:18:41 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file2.txt"
2025/09/03 02:18:41 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:18:41 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:18:42 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file2.txt"
2025/09/03 02:18:42 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/09/03 02:18:44 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:18:44 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/09/03 02:18:44 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:18:44 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:44 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/09/03 02:18:44 NOTICE: (42)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:18:44 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:18:44 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:18:44 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:18:45 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:18:45 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/09/03 02:18:45 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:18:46 INFO  : file1L.txt: Updated modification time in destination
2025/09/03 02:18:46 DEBUG : file1L.txt: Unchanged skipping
2025/09/03 02:18:46 NOTICE: (43)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file2.txt
2025/09/03 02:18:46 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file2.txt"
2025/09/03 02:18:46 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:18:46 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:47 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file2.txt"
2025/09/03 02:18:47 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/09/03 02:18:49 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:18:49 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/09/03 02:18:49 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/09/03 02:18:49 NOTICE: (45)  : bisync resync resync-mode=path1
2025/09/03 02:18:58 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:58 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:18:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:18:58 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:18:58 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:58 INFO  : Copying Path2 files to Path1
2025/09/03 02:18:58 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:18:58 INFO  : There was nothing to transfer
2025/09/03 02:18:58 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:18:59 INFO  : file2.txt: Copied (server-side copy)
2025/09/03 02:18:59 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:18:59 INFO  : Resync updating listings
2025/09/03 02:18:59 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:18:59 INFO  : Bisync successful
2025/09/03 02:18:59 NOTICE: (46)  : test no winner
2025/09/03 02:18:59 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1R.txt
2025/09/03 02:18:59 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:18:59 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/09/03 02:18:59 NOTICE: (48)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file1.txt
2025/09/03 02:18:59 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file1.txt"
2025/09/03 02:18:59 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:18:59 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:19:00 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file1.txt"
2025/09/03 02:19:00 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/09/03 02:19:02 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:19:02 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/09/03 02:19:02 NOTICE: (49)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file2.txt
2025/09/03 02:19:02 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file2.txt"
2025/09/03 02:19:02 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1R.txt"
2025/09/03 02:19:02 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:19:03 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file2.txt"
2025/09/03 02:19:03 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/09/03 02:19:03 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/09/03 02:19:04 INFO  : file1R.txt: Updated modification time in destination
2025/09/03 02:19:04 DEBUG : file1R.txt: Unchanged skipping
2025/09/03 02:19:04 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/021715xi/datadir/test_resync_modes-selonex3/ file1L.txt
2025/09/03 02:19:04 DEBUG : fs cache: switching user supplied name "/tmp/021715xi/datadir/test_resync_modes-selonex3/" for canonical name "/tmp/021715xi/datadir/test_resync_modes-selonex3"
2025/09/03 02:19:04 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/09/03 02:19:04 NOTICE: (51)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/ file1.txt
2025/09/03 02:19:04 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" as "file1.txt"
2025/09/03 02:19:04 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:19:04 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/"
2025/09/03 02:19:05 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1" as "file1.txt"
2025/09/03 02:19:05 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/09/03 02:19:05 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:19:07 INFO  : file1L.txt: Updated modification time in destination
2025/09/03 02:19:07 DEBUG : file1L.txt: Unchanged skipping
2025/09/03 02:19:07 NOTICE: (52)  : copy-as /tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/ file2.txt
2025/09/03 02:19:07 DEBUG : copyFile "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt" to "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/" as "file2.txt"
2025/09/03 02:19:07 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_resync_modes-selonex3/file1L.txt"
2025/09/03 02:19:07 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:19:08 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2" as "file2.txt"
2025/09/03 02:19:08 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/09/03 02:19:10 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/09/03 02:19:10 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/09/03 02:19:10 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/09/03 02:19:10 NOTICE: (54)  : bisync resync resync-mode=newer
2025/09/03 02:19:18 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:19:18 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/09/03 02:19:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/09/03 02:19:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/09/03 02:19:18 INFO  : Synching Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" with Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:19:18 INFO  : Copying Path2 files to Path1
2025/09/03 02:19:18 INFO  : - Path2    Resync is copying files to         - Path1
2025/09/03 02:19:19 INFO  : file1.txt: Copied (server-side copy)
2025/09/03 02:19:19 INFO  : file2.txt: Copied (server-side copy)
2025/09/03 02:19:19 INFO  : - Path1    Resync is copying files to         - Path2
2025/09/03 02:19:19 INFO  : Resync updating listings
2025/09/03 02:19:19 INFO  : Validating listings for Path1 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-pulobiq8pitu/021715xi/resync_modes/path2/"
2025/09/03 02:19:19 INFO  : Bisync successful
2025/09/03 02:19:19 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path1: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:19 DEBUG : RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118d7f57d936e0c2_d20250903_m021723_c001_v0001105_t0030_u01756865843106")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f103bbd73449ea314_d20250903_m021918_c001_v0001120_t0000_u01756865958945")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f109c7ef00273c835_d20250903_m021906_c001_v0001100_t0027_u01756865946982")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1077c210cf0d5fde_d20250903_m021846_c001_v0001092_t0002_u01756865926368")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f106bd71d5ce9efe0_d20250903_m021829_c001_v0001130_t0051_u01756865909406")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f112527a462a29fcb_d20250903_m021823_c001_v0001178_t0048_u01756865903914")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118d53e37c1e62fb_d20250903_m021815_c001_v0001178_t0029_u01756865895507")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f109c206ba551a4b5_d20250903_m021809_c001_v0001131_t0028_u01756865889807")
2025/09/03 02:19:19 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f107d3fc692bf5551_d20250903_m021800_c001_v0001176_t0046_u01756865880831")
2025/09/03 02:19:20 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1136bfc986e8c353_d20250903_m021755_c001_v0001036_t0040_u01756865875010")
2025/09/03 02:19:20 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115cf376fb983b1e_d20250903_m021745_c001_v0001037_t0031_u01756865865777")
2025/09/03 02:19:20 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f105bb132e5002bf2_d20250903_m021730_c001_v0001150_t0059_u01756865850956")
2025/09/03 02:19:20 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497d7b_d20250903_m021723_c001_v0001101_t0017_u01756865843291")
2025/09/03 02:19:20 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f113189a273e1cfa0_d20250903_m021919_c001_v0001181_t0013_u01756865959275")
2025/09/03 02:19:20 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1068125a55d03295_d20250903_m021904_c001_v0001182_t0013_u01756865944511")
2025/09/03 02:19:20 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1099845883a51939_d20250903_m021843_c001_v0001183_t0038_u01756865923928")
2025/09/03 02:19:20 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497d7f_d20250903_m021723_c001_v0001101_t0046_u01756865843483")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f4155448b324f34af_d20250903_m021914_c001_v7007000_t0000_u01756865954476")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118f2a1a3bedd1bf_d20250903_m021913_c001_v0001145_t0041_u01756865953028")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118d7f57d936e260_d20250903_m021910_c001_v0001105_t0036_u01756865950600")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f413665021a04f4d0_d20250903_m021853_c001_v7007000_t0000_u01756865933929")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115c8bdd0bb91a88_d20250903_m021852_c001_v0001043_t0033_u01756865932306")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497ebd_d20250903_m021850_c001_v0001101_t0044_u01756865930030")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f41970b34c7222db6_d20250903_m021833_c001_v7007000_t0000_u01756865913726")
2025/09/03 02:19:20 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1068125a55d031ba_d20250903_m021832_c001_v0001182_t0017_u01756865912496")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497e67_d20250903_m021830_c001_v0001101_t0020_u01756865910252")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f41970b34c7222db2_d20250903_m021819_c001_v7007000_t0000_u01756865899638")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f119c12120ae83ebe_d20250903_m021818_c001_v0001183_t0018_u01756865898441")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118d7f57d936e17e_d20250903_m021816_c001_v0001105_t0047_u01756865896093")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f401c0b60c58582d2_d20250903_m021805_c001_v7007000_t0000_u01756865885336")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1054c4af92e430b5_d20250903_m021803_c001_v0001032_t0035_u01756865883910")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497e0b_d20250903_m021801_c001_v0001101_t0035_u01756865881425")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f4023ff6b65141c4e_d20250903_m021750_c001_v7007000_t0000_u01756865870310")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f114d8b069738f899_d20250903_m021748_c001_v0001099_t0030_u01756865868799")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115f7f186e497dcf_d20250903_m021746_c001_v0001101_t0002_u01756865866511")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f420768a9af7bd622_d20250903_m021735_c001_v7007000_t0000_u01756865855204")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f114274d28886df38_d20250903_m021733_c001_v0001145_t0049_u01756865853798")
2025/09/03 02:19:21 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118d7f57d936e0d8_d20250903_m021731_c001_v0001105_t0013_u01756865851554")
2025/09/03 02:19:22 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/resync_modes/path2: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:22 DEBUG : RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115abfc8ad5845d8_d20250903_m021725_c001_v0001182_t0032_u01756865845130")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110d90370c24347d_d20250903_m021902_c001_v0001184_t0010_u01756865942083")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11645456db4bc891_d20250903_m021858_c001_v0001144_t0000_u01756865938512")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111536b41b1bebca_d20250903_m021841_c001_v0001181_t0043_u01756865921167")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10649528cf8506fc_d20250903_m021838_c001_v0001181_t0048_u01756865918386")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1117eecf4755ba77_d20250903_m021826_c001_v0001176_t0019_u01756865906626")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f118c91649a7dd7ac_d20250903_m021812_c001_v0001093_t0007_u01756865892539")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1056ba48c62dc7e5_d20250903_m021757_c001_v0001171_t0059_u01756865877905")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f100a6ee60ad24d11_d20250903_m021742_c001_v0001091_t0033_u01756865862795")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1109ccc72ef84ac3_d20250903_m021739_c001_v0001146_t0007_u01756865859688")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11536ec6c3521c67_d20250903_m021728_c001_v0001181_t0029_u01756865848177")
2025/09/03 02:19:22 DEBUG : file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110136866a2a262d_d20250903_m021724_c001_v0001178_t0004_u01756865844756")
2025/09/03 02:19:22 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10381faa2495d8c2_d20250903_m021909_c001_v0001172_t0052_u01756865949853")
2025/09/03 02:19:22 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10573189b61cfa36_d20250903_m021858_c001_v0001113_t0006_u01756865938878")
2025/09/03 02:19:22 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f104c154630539519_d20250903_m021849_c001_v0001136_t0027_u01756865929349")
2025/09/03 02:19:23 DEBUG : file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f119f05ecd6b80bbf_d20250903_m021724_c001_v0001113_t0040_u01756865844957")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f421ff5b6efa19463_d20250903_m021918_c001_v7007000_t0000_u01756865958526")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f119eb518b68b53db_d20250903_m021917_c001_v0001041_t0052_u01756865957149")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110136866a2a28f8_d20250903_m021915_c001_v0001178_t0029_u01756865955021")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f4197d5bec168acd2_d20250903_m021857_c001_v7007000_t0000_u01756865937937")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f114ec5a0a9d53220_d20250903_m021856_c001_v0001181_t0024_u01756865936728")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f119f05ecd6b80c9b_d20250903_m021854_c001_v0001113_t0002_u01756865934476")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f403da38b8dc97a82_d20250903_m021837_c001_v7007000_t0000_u01756865917795")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f106f17ef34655c4f_d20250903_m021836_c001_v0001113_t0058_u01756865916466")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115abfc8ad5847b7_d20250903_m021834_c001_v0001182_t0043_u01756865914273")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f4197d5bec168ac07_d20250903_m021823_c001_v7007000_t0000_u01756865903529")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110e624c11d19ae4_d20250903_m021822_c001_v0001183_t0034_u01756865902333")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110136866a2a27c0_d20250903_m021820_c001_v0001178_t0026_u01756865900190")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f40604cba3127dad3_d20250903_m021809_c001_v7007000_t0000_u01756865889413")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f112b564422b0aabb_d20250903_m021808_c001_v0001098_t0008_u01756865888127")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f119f05ecd6b80c27_d20250903_m021805_c001_v0001113_t0026_u01756865885882")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f4055ecea952a09ea_d20250903_m021754_c001_v7007000_t0000_u01756865874625")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11324dacbeee5c6b_d20250903_m021753_c001_v0001116_t0029_u01756865873008")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f115abfc8ad58467d_d20250903_m021750_c001_v0001182_t0023_u01756865870855")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f416953a890eb235e_d20250903_m021739_c001_v7007000_t0000_u01756865859110")
2025/09/03 02:19:23 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f109fbd1e2b2ee8eb_d20250903_m021737_c001_v0001177_t0008_u01756865857908")
2025/09/03 02:19:24 DEBUG : modtime_write_test: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110136866a2a268d_d20250903_m021735_c001_v0001178_t0015_u01756865855759")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/09/03 02:19:24 INFO  : path1: Making directory
2025/09/03 02:19:24 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/rmdirs/path1"
2025/09/03 02:19:25 INFO  : path2: Making directory
2025/09/03 02:19:25 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/rmdirs/path2"
2025/09/03 02:19:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/09/03 02:19:26 DEBUG : Creating backend with remote "/tmp/021715xi/initdir/test_rmdirs-jadosuw5"
2025/09/03 02:19:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : subdir: Making directory with metadata
2025/09/03 02:19:26 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/09/03 02:19:26 INFO  : file1.copy1.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/09/03 02:19:26 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/09/03 02:19:26 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/09/03 02:19:26 INFO  : file1.copy2.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:19:26 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:19:26 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:26 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/09/03 02:19:26 INFO  : file1.copy4.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : Local file system at /tmp/021715xi/initdir/test_rmdirs-jadosuw5: Waiting for checks to finish
2025/09/03 02:19:26 DEBUG : Local file system at /tmp/021715xi/initdir/test_rmdirs-jadosuw5: Waiting for transfers to finish
2025/09/03 02:19:26 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:26 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:19:26 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/09/03 02:19:26 INFO  : file1.copy3.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/09/03 02:19:26 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:19:26 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/09/03 02:19:26 INFO  : file1.copy5.txt: Copied (new)
2025/09/03 02:19:26 INFO  : subdir: Set directory modification time (using SetModTime)
2025/09/03 02:19:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/09/03 02:19:26 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_rmdirs-horonoh0"
2025/09/03 02:19:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:26 DEBUG : Local file system at /tmp/021715xi/datadir/test_rmdirs-horonoh0: Waiting for checks to finish
2025/09/03 02:19:26 DEBUG : Local file system at /tmp/021715xi/datadir/test_rmdirs-horonoh0: Waiting for transfers to finish
2025/09/03 02:19:26 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:26 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/09/03 02:19:26 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:26 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path1: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:27 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path2: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:28 NOTICE: checking initFs Local file system at /tmp/021715xi/initdir/test_rmdirs-jadosuw5
2025/09/03 02:19:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 INFO  : subdir: Making directory
2025/09/03 02:19:28 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:28 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path1: Waiting for checks to finish
2025/09/03 02:19:28 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path1: Waiting for transfers to finish
2025/09/03 02:19:29 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:29 INFO  : file1.copy1.txt: Copied (new)
2025/09/03 02:19:29 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:29 INFO  : file1.copy4.txt: Copied (new)
2025/09/03 02:19:29 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:29 INFO  : file1.copy3.txt: Copied (new)
2025/09/03 02:19:29 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:29 INFO  : file1.copy2.txt: Copied (new)
2025/09/03 02:19:29 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:29 INFO  : file1.copy5.txt: Copied (new)
2025/09/03 02:19:30 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:19:30 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:30 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:30 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:30 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:30 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:19:30 NOTICE: checking Path1 B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path1
2025/09/03 02:19:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 INFO  : subdir: Making directory
2025/09/03 02:19:30 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:30 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path2: Waiting for checks to finish
2025/09/03 02:19:30 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path2: Waiting for transfers to finish
2025/09/03 02:19:31 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:31 INFO  : file1.copy1.txt: Copied (new)
2025/09/03 02:19:31 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:31 INFO  : file1.copy4.txt: Copied (new)
2025/09/03 02:19:32 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:32 INFO  : file1.copy2.txt: Copied (new)
2025/09/03 02:19:32 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:32 INFO  : file1.copy5.txt: Copied (new)
2025/09/03 02:19:32 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:32 INFO  : subdir/file20.txt: Copied (new)
2025/09/03 02:19:32 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:32 INFO  : file1.copy3.txt: Copied (new)
2025/09/03 02:19:32 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:19:32 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:32 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:32 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:32 NOTICE: checking path2 B2 bucket rclone-test-pulobiq8pitu path 021715xi/rmdirs/path2
2025/09/03 02:19:32 NOTICE: (01)  : test rmdirs
2025/09/03 02:19:32 NOTICE: (02)  : test initial bisync
2025/09/03 02:19:32 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/09/03 02:19:32 INFO  : path1: Making directory
2025/09/03 02:19:32 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/volatile/path1"
2025/09/03 02:19:33 INFO  : path2: Making directory
2025/09/03 02:19:33 DEBUG : Creating backend with remote "TestB2:rclone-test-pulobiq8pitu/021715xi/volatile/path2"
2025/09/03 02:19:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/09/03 02:19:34 DEBUG : Creating backend with remote "/tmp/021715xi/initdir/test_volatile-xenipes7"
2025/09/03 02:19:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : Local file system at /tmp/021715xi/initdir/test_volatile-xenipes7: Waiting for checks to finish
2025/09/03 02:19:34 DEBUG : Local file system at /tmp/021715xi/initdir/test_volatile-xenipes7: Waiting for transfers to finish
2025/09/03 02:19:34 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/09/03 02:19:34 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/09/03 02:19:34 INFO  : file3.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/09/03 02:19:34 INFO  : file2.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/09/03 02:19:34 INFO  : file6.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/09/03 02:19:34 INFO  : file5.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/09/03 02:19:34 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/09/03 02:19:34 INFO  : file7.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/09/03 02:19:34 INFO  : file8.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/09/03 02:19:34 INFO  : file4.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/09/03 02:19:34 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/09/03 02:19:34 DEBUG : Creating backend with remote "/tmp/021715xi/datadir/test_volatile-mulibaf3"
2025/09/03 02:19:34 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:34 DEBUG : Local file system at /tmp/021715xi/datadir/test_volatile-mulibaf3: Waiting for checks to finish
2025/09/03 02:19:34 DEBUG : Local file system at /tmp/021715xi/datadir/test_volatile-mulibaf3: Waiting for transfers to finish
2025/09/03 02:19:34 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/09/03 02:19:34 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:34 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:34 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/09/03 02:19:34 INFO  : dummy.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:34 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/09/03 02:19:34 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/09/03 02:19:34 INFO  : file11.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/09/03 02:19:34 INFO  : file10.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/09/03 02:19:34 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/09/03 02:19:34 INFO  : file5L.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/09/03 02:19:34 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/09/03 02:19:34 INFO  : file2.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/09/03 02:19:34 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/09/03 02:19:34 INFO  : file5R.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:34 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/09/03 02:19:34 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/09/03 02:19:34 INFO  : file7.txt: Copied (new)
2025/09/03 02:19:34 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/09/03 02:19:34 INFO  : file6.txt: Copied (new)
2025/09/03 02:19:34 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path1: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:35 INFO  : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path2: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:36 NOTICE: checking initFs Local file system at /tmp/021715xi/initdir/test_volatile-xenipes7
2025/09/03 02:19:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:36 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path1: Waiting for checks to finish
2025/09/03 02:19:36 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path1: Waiting for transfers to finish
2025/09/03 02:19:37 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:37 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:37 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:37 INFO  : file2.txt: Copied (new)
2025/09/03 02:19:37 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:37 INFO  : file4.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:38 INFO  : file5.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:38 INFO  : file6.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:38 INFO  : file3.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:38 INFO  : file7.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:38 INFO  : file8.txt: Copied (new)
2025/09/03 02:19:38 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:19:38 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:38 NOTICE: checking Path1 B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path1
2025/09/03 02:19:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/09/03 02:19:38 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path2: Waiting for checks to finish
2025/09/03 02:19:38 DEBUG : B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path2: Waiting for transfers to finish
2025/09/03 02:19:39 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:39 INFO  : file1.txt: Copied (new)
2025/09/03 02:19:39 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:39 INFO  : file3.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file2.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file4.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file5.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/09/03 02:19:40 INFO  : RCLONE_TEST: Copied (new)
2025/09/03 02:19:40 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file8.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file7.txt: Copied (new)
2025/09/03 02:19:40 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/09/03 02:19:40 INFO  : file6.txt: Copied (new)
2025/09/03 02:19:40 NOTICE: checking path2 B2 bucket rclone-test-pulobiq8pitu path 021715xi/volatile/path2
2025/09/03 02:19:40 NOTICE: (01)  : test volatile
2025/09/03 02:19:40 NOTICE: (02)  : test initial bisync
2025/09/03 02:19:40 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/09/03 02:19:40 DEBUG : B2 bucket rclone-test-pulobiq8pitu: Purge remote
2025/09/03 02:19:40 INFO  : B2 bucket rclone-test-pulobiq8pitu: cleaning bucket "rclone-test-pulobiq8pitu" of all files
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f112552f82b798c70_d20250903_m021929_c001_v0001180_t0054_u01756865969916")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111536b41b1becbf_d20250903_m021929_c001_v0001181_t0021_u01756865969349")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1115b3c35aeee1af_d20250903_m021929_c001_v0001130_t0019_u01756865969542")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111e7bf528553087_d20250903_m021929_c001_v0001180_t0010_u01756865969730")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111536b41b1becc1_d20250903_m021929_c001_v0001181_t0056_u01756865969625")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111536b41b1becc3_d20250903_m021929_c001_v0001181_t0053_u01756865969834")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f111e7bf528553089_d20250903_m021929_c001_v0001180_t0041_u01756865969951")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1115b3c35aeee1b1_d20250903_m021930_c001_v0001130_t0042_u01756865970018")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110e7863e9c21d7a_d20250903_m021932_c001_v0001101_t0017_u01756865972115")
2025/09/03 02:19:41 DEBUG : 021715xi/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110d90370c243575_d20250903_m021931_c001_v0001184_t0046_u01756865971544")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f100c3020cdbffa22_d20250903_m021931_c001_v0001036_t0013_u01756865971732")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1097200b9351508d_d20250903_m021931_c001_v0001154_t0003_u01756865971919")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110d90370c243579_d20250903_m021931_c001_v0001184_t0046_u01756865971758")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110d90370c24357b_d20250903_m021931_c001_v0001184_t0019_u01756865971952")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f100c3020cdbffa24_d20250903_m021932_c001_v0001036_t0000_u01756865972094")
2025/09/03 02:19:42 DEBUG : 021715xi/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f110d90370c243581_d20250903_m021932_c001_v0001184_t0023_u01756865972205")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10098ba8eac04517_d20250903_m021938_c001_v0001120_t0002_u01756865978088")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f107f3da4d09ddaa8_d20250903_m021937_c001_v0001181_t0058_u01756865977526")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1104351f6a947631_d20250903_m021937_c001_v0001180_t0010_u01756865977714")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file3.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10669444840d8f1d_d20250903_m021937_c001_v0001134_t0054_u01756865977906")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file4.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f107f3da4d09ddaac_d20250903_m021937_c001_v0001181_t0032_u01756865977795")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file5.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1104351f6a947635_d20250903_m021937_c001_v0001180_t0033_u01756865977908")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file6.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f107f3da4d09ddaae_d20250903_m021938_c001_v0001181_t0035_u01756865978008")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file7.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1104351f6a947639_d20250903_m021938_c001_v0001180_t0037_u01756865978114")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path1/file8.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f107f3da4d09ddab2_d20250903_m021938_c001_v0001181_t0028_u01756865978244")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path2/RCLONE_TEST: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1142fa9065a3dc67_d20250903_m021939_c001_v0001105_t0018_u01756865979959")
2025/09/03 02:19:42 DEBUG : 021715xi/volatile/path2/file1.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1195da34b1824d7c_d20250903_m021939_c001_v0001105_t0043_u01756865979403")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file2.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10155f816723c99c_d20250903_m021939_c001_v0001172_t0020_u01756865979776")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file3.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11319cde31d3d875_d20250903_m021939_c001_v0001161_t0050_u01756865979648")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file4.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1195da34b1824d7e_d20250903_m021939_c001_v0001105_t0049_u01756865979928")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file5.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11319cde31d3d879_d20250903_m021940_c001_v0001161_t0034_u01756865980048")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file6.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f10155f816723c9a0_d20250903_m021940_c001_v0001172_t0000_u01756865980139")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file7.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f1195da34b1824d82_d20250903_m021940_c001_v0001105_t0045_u01756865980339")
2025/09/03 02:19:43 DEBUG : 021715xi/volatile/path2/file8.txt: Deleting (id "4_z8fe23d9350dd0bdb9f980e1b_f11319cde31d3d87b_d20250903_m021940_c001_v0001161_t0046_u01756865980351")
--- PASS: TestBisyncRemoteRemote (151.76s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (128.50s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.31s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.21s)
FAIL
2025/09/03 02:19:47 DEBUG : B2 bucket rclone-test-vunitiz6dike: Purge remote
2025/09/03 02:19:47 INFO  : B2 bucket rclone-test-vunitiz6dike: cleaning bucket "rclone-test-vunitiz6dike" of all files
2025/09/03 02:19:47 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteLocal$/^extended_filenames$|^TestBisyncRemoteRemote$/^(resync_modes|rmdirs|volatile)$'" - Finished ERROR in 3m39.872815253s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/check_access_filters]