"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(normalization|rmdirs|volatile)$'" - Starting (try 2/5) 2025/12/25 02:55:42 DEBUG : Creating backend with remote "TestB2:rclone-test-wadunib0faji" 2025/12/25 02:55:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/25 02:55:43 DEBUG : Creating backend with remote "/tmp/rclone1952686370" === RUN TestBisyncRemoteRemote 2025/12/25 02:55:43 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute" 2025/12/25 02:55:43 NOTICE: remote: TestB2:rclone-test-qicarik1vute === RUN TestBisyncRemoteRemote/normalization 2025/12/25 02:55:43 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne" 2025/12/25 02:55:45 INFO : path1: Making directory 2025/12/25 02:55:45 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1" 2025/12/25 02:55:47 INFO : path2: Making directory 2025/12/25 02:55:47 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2" 2025/12/25 02:55:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/initial" 2025/12/25 02:55:48 DEBUG : Creating backend with remote "/tmp/025543ne/initdir/test_normalization-rujoceh8" 2025/12/25 02:55:48 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2025/12/25 02:55:48 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z) 2025/12/25 02:55:48 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2025/12/25 02:55:48 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:55:48 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/12/25 02:55:48 INFO : file1.txt: Copied (new) 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: size = 272 OK 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: size = 0 OK 2025/12/25 02:55:48 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:55:48 DEBUG : Local file system at /tmp/025543ne/initdir/test_normalization-rujoceh8: Waiting for checks to finish 2025/12/25 02:55:48 DEBUG : Local file system at /tmp/025543ne/initdir/test_normalization-rujoceh8: Waiting for transfers to finish 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2025/12/25 02:55:48 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2025/12/25 02:55:48 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/12/25 02:55:48 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/12/25 02:55:48 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/12/25 02:55:48 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: size = 272 OK 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2025/12/25 02:55:48 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2025/12/25 02:55:48 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2025/12/25 02:55:48 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2025/12/25 02:55:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/modfiles" 2025/12/25 02:55:48 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:55:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:48 DEBUG : Local file system at /tmp/025543ne/datadir/test_normalization-moramiq7: Waiting for checks to finish 2025/12/25 02:55:48 DEBUG : Local file system at /tmp/025543ne/datadir/test_normalization-moramiq7: Waiting for transfers to finish 2025/12/25 02:55:48 DEBUG : file1.txt.75b77f44.partial: size = 19 OK 2025/12/25 02:55:48 DEBUG : 測試_filtersfile.txt.3ada41b4.partial: size = 254 OK 2025/12/25 02:55:48 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:55:48 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2025/12/25 02:55:48 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/12/25 02:55:48 DEBUG : 測試_filtersfile.txt.3ada41b4.partial: renamed to: 測試_filtersfile.txt 2025/12/25 02:55:48 INFO : file1.txt: Copied (new) 2025/12/25 02:55:48 INFO : 測試_filtersfile.txt: Copied (new) 2025/12/25 02:55:48 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:55:48 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:55:49 NOTICE: checking initFs Local file system at /tmp/025543ne/initdir/test_normalization-rujoceh8 2025/12/25 02:55:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:55:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:50 INFO : 測試_Русский_ _ _ě_áñ: Making directory 2025/12/25 02:55:50 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:50 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:50 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2025/12/25 02:55:50 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Waiting for checks to finish 2025/12/25 02:55:50 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Waiting for transfers to finish 2025/12/25 02:55:50 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:55:50 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:55:50 INFO : file1.txt: Copied (new) 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: size = 272 OK 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2025/12/25 02:55:51 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: size = 0 OK 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:55:51 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2025/12/25 02:55:51 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:55:51 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:55:51 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: size = 272 OK 2025/12/25 02:55:51 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2025/12/25 02:55:51 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2025/12/25 02:55:51 NOTICE: checking Path1 B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1 2025/12/25 02:55:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:55:52 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:52 INFO : 測試_Русский_ _ _ě_áñ: Making directory 2025/12/25 02:55:52 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:52 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2025/12/25 02:55:52 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2025/12/25 02:55:52 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Waiting for checks to finish 2025/12/25 02:55:52 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Waiting for transfers to finish 2025/12/25 02:55:53 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:55:53 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:55:53 INFO : file1.txt: Copied (new) 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: size = 0 OK 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:55:53 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: size = 272 OK 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2025/12/25 02:55:53 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2025/12/25 02:55:53 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:55:53 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:55:53 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: size = 272 OK 2025/12/25 02:55:53 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2025/12/25 02:55:53 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2025/12/25 02:55:53 NOTICE: checking path2 B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2 2025/12/25 02:55:53 NOTICE: (01) : test normalization 2025/12/25 02:55:53 NOTICE: (02) : touch-copy 2001-01-02 /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:55:53 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/" 2025/12/25 02:55:53 DEBUG : fs cache: renaming cache item "/tmp/025543ne/datadir/test_normalization-moramiq7/" to be canonical "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:55:53 DEBUG : file1.txt: Set modification time 2001-01-02 2025/12/25 02:55:53 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "" 2025/12/25 02:55:53 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:55:53 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:55:55 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file1.txt" 2025/12/25 02:55:55 DEBUG : file1.txt: size = 19 (Local file system at /tmp/025543ne/datadir/test_normalization-moramiq7) 2025/12/25 02:55:55 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2) 2025/12/25 02:55:55 DEBUG : file1.txt: Sizes differ 2025/12/25 02:55:56 DEBUG : file1.txt: size = 19 OK 2025/12/25 02:55:56 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:55:56 INFO : file1.txt: Copied (replaced existing) 2025/12/25 02:55:56 NOTICE: (03) : test initial bisync 2025/12/25 02:55:56 NOTICE: (04) : bisync resync 2025/12/25 02:56:04 DEBUG : Creating backend with remote "/tmp/025543ne" 2025/12/25 02:56:06 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:56:06 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:56:06 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:56:07 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:56:07 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:56:07 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:56:07 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:56:07 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:56:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:56:07 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/12/25 02:56:07 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:07 INFO : Copying Path2 files to Path1 2025/12/25 02:56:07 INFO : - Path2 Resync is copying files to - Path1 2025/12/25 02:56:08 INFO : - Path1 Resync is copying files to - Path2 2025/12/25 02:56:08 INFO : file1.txt: Copied (server-side copy) 2025/12/25 02:56:08 INFO : Resync updating listings 2025/12/25 02:56:08 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:08 INFO : Bisync successful 2025/12/25 02:56:08 NOTICE: (05) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2025/12/25 02:56:08 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:56:08 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:08 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" 2025/12/25 02:56:10 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:56:10 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:11 DEBUG : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: size = 19 OK 2025/12/25 02:56:11 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:11 INFO : file1.txt: Copied (new) to: 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2025/12/25 02:56:11 NOTICE: (06) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder éééö.txt 2025/12/25 02:56:11 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder" as "éééö.txt" 2025/12/25 02:56:11 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:11 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder" 2025/12/25 02:56:13 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/folder" as "éééö.txt" 2025/12/25 02:56:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:14 DEBUG : éééö.txt: size = 19 OK 2025/12/25 02:56:14 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:14 INFO : file1.txt: Copied (new) to: éééö.txt 2025/12/25 02:56:14 NOTICE: (07) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder HeLlO,wOrLd!.txt 2025/12/25 02:56:14 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:56:14 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:14 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/folder" 2025/12/25 02:56:15 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/folder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:56:16 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:17 DEBUG : HeLlO,wOrLd!.txt: size = 19 OK 2025/12/25 02:56:17 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:17 INFO : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt 2025/12/25 02:56:17 NOTICE: (08) : touch-copy 2001-01-03 /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:56:17 DEBUG : fs cache: switching user supplied name "/tmp/025543ne/datadir/test_normalization-moramiq7/" for canonical name "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:56:17 DEBUG : file1.txt: Set modification time 2001-01-03 2025/12/25 02:56:17 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "" 2025/12/25 02:56:17 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:17 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:18 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file1.txt" 2025/12/25 02:56:19 DEBUG : file1.txt: size = 19 (Local file system at /tmp/025543ne/datadir/test_normalization-moramiq7) 2025/12/25 02:56:19 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2) 2025/12/25 02:56:19 DEBUG : file1.txt: Sizes differ 2025/12/25 02:56:20 DEBUG : file1.txt: size = 19 OK 2025/12/25 02:56:20 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:20 INFO : file1.txt: Copied (replaced existing) 2025/12/25 02:56:20 NOTICE: (09) : copy-as-NFD /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder éééö.txt 2025/12/25 02:56:20 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder" as "éééö.txt" 2025/12/25 02:56:20 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:20 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder" 2025/12/25 02:56:21 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2/folder" as "éééö.txt" 2025/12/25 02:56:21 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:23 DEBUG : éééö.txt: size = 19 OK 2025/12/25 02:56:23 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:23 INFO : file1.txt: Copied (new) to: éééö.txt 2025/12/25 02:56:23 NOTICE: (10) : copy-as-NFD /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder hello,WORLD!.txt 2025/12/25 02:56:23 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder" as "hello,WORLD!.txt" 2025/12/25 02:56:23 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:23 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/folder" 2025/12/25 02:56:24 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2/folder" as "hello,WORLD!.txt" 2025/12/25 02:56:24 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:26 DEBUG : hello,WORLD!.txt: size = 19 OK 2025/12/25 02:56:26 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:26 INFO : file1.txt: Copied (new) to: hello,WORLD!.txt 2025/12/25 02:56:26 NOTICE: (11) : test bisync run with fix-case 2025/12/25 02:56:26 NOTICE: (12) : bisync fix-case 2025/12/25 02:56:37 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:56:37 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:56:37 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:56:38 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:56:38 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:56:38 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:56:38 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:56:38 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:56:38 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:56:38 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/12/25 02:56:38 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:38 INFO : Building Path1 and Path2 listings 2025/12/25 02:56:39 INFO : Path1 checking for diffs 2025/12/25 02:56:39 INFO : - Path1 File is new - folder/HeLlO,wOrLd!.txt 2025/12/25 02:56:39 INFO : - Path1 File is new - folder/éééö.txt 2025/12/25 02:56:39 INFO : - Path1 File is new - "測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:56:39 INFO : Path1: 3 changes:  3 new,  0 modified,  0 deleted 2025/12/25 02:56:39 INFO : Path2 checking for diffs 2025/12/25 02:56:39 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/12/25 02:56:39 INFO : - Path2 File is new - folder/éééö.txt 2025/12/25 02:56:39 INFO : - Path2 File is new - folder/hello,WORLD!.txt 2025/12/25 02:56:39 INFO : Path2: 3 changes:  2 new,  1 modified,  0 deleted 2025/12/25 02:56:39 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/12/25 02:56:39 INFO : Applying changes 2025/12/25 02:56:39 INFO : Checking potential conflicts... 2025/12/25 02:56:40 NOTICE: B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: 0 differences found 2025/12/25 02:56:40 NOTICE: B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: 2 matching files 2025/12/25 02:56:40 INFO : Finished checking the potential conflicts. %!s() 2025/12/25 02:56:40 NOTICE: - WARNING New or changed in both paths - folder/HeLlO,wOrLd!.txt 2025/12/25 02:56:40 INFO : folder/hello,WORLD!.txt: Files are equal but will copy anyway to fix case to folder/HeLlO,wOrLd!.txt 2025/12/25 02:56:40 NOTICE: - WARNING New or changed in both paths - folder/éééö.txt 2025/12/25 02:56:40 INFO : folder/éééö.txt: Files are equal but will copy anyway to fix case to folder/éééö.txt 2025/12/25 02:56:40 INFO : - Path1 Queue copy to Path2 - "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:56:40 INFO : - Path2 Queue copy to Path1 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/file1.txt 2025/12/25 02:56:40 INFO : - Path2 Do queued copies to - Path1 2025/12/25 02:56:40 INFO : file1.txt: Copied (server-side copy) 2025/12/25 02:56:40 INFO : - Path1 Do queued copies to - Path2 2025/12/25 02:56:41 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy) 2025/12/25 02:56:41 INFO : folder/HeLlO,wOrLd!.txt: Updated modification time in destination 2025/12/25 02:56:41 INFO : folder/éééö.txt: Updated modification time in destination 2025/12/25 02:56:42 INFO : folder/éééö.txt: Copied (server-side copy) to: folder/éééö.txt 2025/12/25 02:56:42 INFO : folder/hello,WORLD!.txt: Copied (server-side copy) to: folder/HeLlO,wOrLd!.txt 2025/12/25 02:56:42 INFO : folder/éééö.txt: Deleted 2025/12/25 02:56:42 INFO : folder/éééö.txt: Fixed case by renaming to: folder/éééö.txt 2025/12/25 02:56:42 INFO : folder/hello,WORLD!.txt: Deleted 2025/12/25 02:56:42 INFO : folder/hello,WORLD!.txt: Fixed case by renaming to: folder/HeLlO,wOrLd!.txt 2025/12/25 02:56:42 INFO : Updating listings 2025/12/25 02:56:42 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:42 INFO : Bisync successful 2025/12/25 02:56:42 NOTICE: (13) : purge-children TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/ 2025/12/25 02:56:42 DEBUG : RCLONE_TEST: Remove file 2025/12/25 02:56:42 DEBUG : file1.txt: Remove file 2025/12/25 02:56:43 DEBUG : folder: Purge subdir 2025/12/25 02:56:43 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:43 DEBUG : folder/HeLlO,wOrLd!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1065b8be78eb261a_d20251225_m025617_c001_v0001173_t0042_u01766631377495") 2025/12/25 02:56:43 DEBUG : folder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f112e98c65a822ec4_d20251225_m025614_c001_v0001183_t0047_u01766631374704") 2025/12/25 02:56:43 DEBUG : 測試_Русский_ _ _ě_áñ: Purge subdir 2025/12/25 02:56:43 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:43 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0b71_d20251225_m025550_c001_v0001185_t0050_u01766631350998") 2025/12/25 02:56:43 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d3fafa_d20251225_m025551_c001_v0001173_t0038_u01766631351406") 2025/12/25 02:56:43 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0b7d_d20251225_m025551_c001_v0001185_t0039_u01766631351185") 2025/12/25 02:56:43 DEBUG : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Purge subdir 2025/12/25 02:56:43 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:44 DEBUG : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11206071cf2cf4c3_d20251225_m025611_c001_v0001167_t0027_u01766631371656") 2025/12/25 02:56:44 NOTICE: (14) : purge-children TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:56:44 DEBUG : RCLONE_TEST: Remove file 2025/12/25 02:56:44 DEBUG : file1.txt: Remove file 2025/12/25 02:56:44 DEBUG : folder: Purge subdir 2025/12/25 02:56:44 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:45 DEBUG : folder/HeLlO,wOrLd!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f113d509e9658c915_d20251225_m025641_c001_v0001167_t0022_u01766631401987") 2025/12/25 02:56:45 DEBUG : folder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f40284e022be9dcfa_d20251225_m025642_c001_v7007000_t0000_u01766631402365") 2025/12/25 02:56:45 DEBUG : folder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1065b8be78eb282d_d20251225_m025641_c001_v0001173_t0057_u01766631401644") 2025/12/25 02:56:45 DEBUG : folder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f111a6b7507fb71df_d20251225_m025623_c001_v0001185_t0053_u01766631383359") 2025/12/25 02:56:45 DEBUG : folder/hello,WORLD!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f401ef7717c4be5d1_d20251225_m025642_c001_v7007000_t0000_u01766631402518") 2025/12/25 02:56:45 DEBUG : folder/hello,WORLD!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04f09d_d20251225_m025641_c001_v0001173_t0031_u01766631401650") 2025/12/25 02:56:45 DEBUG : folder/hello,WORLD!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10376e6e5524e3c7_d20251225_m025626_c001_v0001029_t0038_u01766631386271") 2025/12/25 02:56:45 DEBUG : folder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f117eca097de5700f_d20251225_m025642_c001_v0001105_t0032_u01766631402052") 2025/12/25 02:56:45 DEBUG : 測試_Русский_ _ _ě_áñ: Purge subdir 2025/12/25 02:56:45 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:45 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04ed12_d20251225_m025553_c001_v0001173_t0001_u01766631353114") 2025/12/25 02:56:45 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1135f0640bf32655_d20251225_m025553_c001_v0001173_t0026_u01766631353491") 2025/12/25 02:56:45 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108d54f537a44fdb_d20251225_m025553_c001_v0001185_t0022_u01766631353125") 2025/12/25 02:56:46 DEBUG : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Purge subdir 2025/12/25 02:56:46 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:56:46 DEBUG : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1163a5d267335a9c_d20251225_m025641_c001_v0001183_t0039_u01766631401037") 2025/12/25 02:56:46 NOTICE: (15) : touch-copy 2001-01-02 /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:56:46 DEBUG : fs cache: switching user supplied name "/tmp/025543ne/datadir/test_normalization-moramiq7/" for canonical name "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:56:46 DEBUG : file1.txt: Set modification time 2001-01-02 2025/12/25 02:56:46 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "" 2025/12/25 02:56:46 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:46 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:47 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file1.txt" 2025/12/25 02:56:47 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:49 DEBUG : file1.txt: size = 19 OK 2025/12/25 02:56:49 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:49 INFO : file1.txt: Copied (new) 2025/12/25 02:56:49 NOTICE: (16) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ file2.txt 2025/12/25 02:56:49 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "file2.txt" 2025/12/25 02:56:49 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:49 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:56:50 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file2.txt" 2025/12/25 02:56:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:52 DEBUG : file2.txt: size = 19 OK 2025/12/25 02:56:52 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:52 INFO : file1.txt: Copied (new) to: file2.txt 2025/12/25 02:56:52 NOTICE: (17) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/ file3.txt 2025/12/25 02:56:52 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" as "file3.txt" 2025/12/25 02:56:52 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:56:52 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" 2025/12/25 02:56:53 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1" as "file3.txt" 2025/12/25 02:56:53 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:56:55 DEBUG : file3.txt: size = 19 OK 2025/12/25 02:56:55 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:56:55 INFO : file1.txt: Copied (new) to: file3.txt 2025/12/25 02:56:55 NOTICE: (18) : bisync resync 2025/12/25 02:57:04 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:04 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:04 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:06 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:06 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:06 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:06 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:06 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:06 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:57:06 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/12/25 02:57:06 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:06 INFO : Copying Path2 files to Path1 2025/12/25 02:57:06 INFO : - Path2 Resync is copying files to - Path1 2025/12/25 02:57:07 INFO : file1.txt: Copied (server-side copy) 2025/12/25 02:57:07 INFO : file2.txt: Copied (server-side copy) 2025/12/25 02:57:07 INFO : - Path1 Resync is copying files to - Path2 2025/12/25 02:57:08 INFO : file3.txt: Copied (server-side copy) 2025/12/25 02:57:08 INFO : Resync updating listings 2025/12/25 02:57:08 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:08 INFO : Bisync successful 2025/12/25 02:57:08 NOTICE: (19) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2025/12/25 02:57:08 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:08 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:08 DEBUG : Config file has changed externally - reloading 2025/12/25 02:57:08 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" 2025/12/25 02:57:09 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:09 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:57:11 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: size = 19 OK 2025/12/25 02:57:11 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:11 INFO : file1.txt: Copied (new) to: 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2025/12/25 02:57:11 NOTICE: (20) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder éééö.txt 2025/12/25 02:57:11 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" as "éééö.txt" 2025/12/25 02:57:11 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:11 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" 2025/12/25 02:57:12 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/newfolder" as "éééö.txt" 2025/12/25 02:57:12 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:57:14 DEBUG : éééö.txt: size = 19 OK 2025/12/25 02:57:14 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:14 INFO : file1.txt: Copied (new) to: éééö.txt 2025/12/25 02:57:14 NOTICE: (21) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder HeLlO,wOrLd!.txt 2025/12/25 02:57:14 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:57:14 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:14 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" 2025/12/25 02:57:15 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/newfolder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:57:15 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:57:17 DEBUG : HeLlO,wOrLd!.txt: size = 19 OK 2025/12/25 02:57:17 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:17 INFO : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt 2025/12/25 02:57:17 NOTICE: (22) : touch-copy 2001-01-03 /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:57:17 DEBUG : fs cache: switching user supplied name "/tmp/025543ne/datadir/test_normalization-moramiq7/" for canonical name "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:57:17 DEBUG : file1.txt: Set modification time 2001-01-03 2025/12/25 02:57:17 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "" 2025/12/25 02:57:17 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:17 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:18 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file1.txt" 2025/12/25 02:57:18 DEBUG : file1.txt: size = 19 OK 2025/12/25 02:57:18 DEBUG : file1.txt: Modification times differ by -24h0m0s: 2001-01-03 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC 2025/12/25 02:57:18 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:19 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:57:19 DEBUG : file1.txt: Unchanged skipping 2025/12/25 02:57:19 NOTICE: (23) : copy-as-NFD /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder éééö.txt 2025/12/25 02:57:19 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder" as "éééö.txt" 2025/12/25 02:57:19 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:19 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder" 2025/12/25 02:57:20 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2/newfolder" as "éééö.txt" 2025/12/25 02:57:20 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:57:22 DEBUG : éééö.txt: size = 19 OK 2025/12/25 02:57:22 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:22 INFO : file1.txt: Copied (new) to: éééö.txt 2025/12/25 02:57:22 NOTICE: (24) : copy-as-NFD /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder hello,WORLD!.txt 2025/12/25 02:57:22 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder" as "hello,WORLD!.txt" 2025/12/25 02:57:22 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:22 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder" 2025/12/25 02:57:23 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2/newfolder" as "hello,WORLD!.txt" 2025/12/25 02:57:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:57:25 DEBUG : hello,WORLD!.txt: size = 19 OK 2025/12/25 02:57:25 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:25 INFO : file1.txt: Copied (new) to: hello,WORLD!.txt 2025/12/25 02:57:25 NOTICE: (25) : test bisync run with normalization 2025/12/25 02:57:25 NOTICE: (26) : bisync norm force 2025/12/25 02:57:34 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:34 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:34 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:36 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:36 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:36 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:37 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:37 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:57: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/12/25 02:57:37 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:37 INFO : Building Path1 and Path2 listings 2025/12/25 02:57:37 INFO : Path1 checking for diffs 2025/12/25 02:57:37 INFO : - Path1 File is new - newfolder/HeLlO,wOrLd!.txt 2025/12/25 02:57:37 INFO : - Path1 File is new - newfolder/éééö.txt 2025/12/25 02:57:37 INFO : - Path1 File is new - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:37 INFO : Path1: 3 changes:  3 new,  0 modified,  0 deleted 2025/12/25 02:57:37 INFO : Path2 checking for diffs 2025/12/25 02:57:37 INFO : - Path2 File changed: time (newer) - file1.txt 2025/12/25 02:57:37 INFO : - Path2 File is new - newfolder/éééö.txt 2025/12/25 02:57:37 INFO : - Path2 File is new - newfolder/hello,WORLD!.txt 2025/12/25 02:57:37 INFO : Path2: 3 changes:  2 new,  1 modified,  0 deleted 2025/12/25 02:57:37 INFO : (Modified:  1 newer,  0 older) 2025/12/25 02:57:37 INFO : Applying changes 2025/12/25 02:57:37 INFO : Checking potential conflicts... 2025/12/25 02:57:37 NOTICE: B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: 0 differences found 2025/12/25 02:57:37 NOTICE: B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: 2 matching files 2025/12/25 02:57:37 INFO : Finished checking the potential conflicts. %!s() 2025/12/25 02:57:37 NOTICE: - WARNING New or changed in both paths - newfolder/HeLlO,wOrLd!.txt 2025/12/25 02:57:37 INFO : newfolder/HeLlO,wOrLd!.txt: Files are equal but will copy anyway to update modtime (will not rename) 2025/12/25 02:57:37 INFO : - Path2 Queue copy to Path1 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder/HeLlO,wOrLd!.txt 2025/12/25 02:57:37 NOTICE: - WARNING New or changed in both paths - newfolder/éééö.txt 2025/12/25 02:57:37 INFO : newfolder/éééö.txt: Files are equal but will copy anyway to update modtime (will not rename) 2025/12/25 02:57:37 INFO : - Path2 Queue copy to Path1 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder/éééö.txt 2025/12/25 02:57:37 INFO : - Path1 Queue copy to Path2 - "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:37 INFO : - Path2 Queue copy to Path1 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/file1.txt 2025/12/25 02:57:37 INFO : - Path2 Do queued copies to - Path1 2025/12/25 02:57:38 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:57:39 INFO : newfolder/éééö.txt: Updated modification time in destination 2025/12/25 02:57:39 INFO : newfolder/hello,WORLD!.txt: Updated modification time in destination 2025/12/25 02:57:39 INFO : - Path1 Do queued copies to - Path2 2025/12/25 02:57:39 INFO : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy) 2025/12/25 02:57:39 INFO : Updating listings 2025/12/25 02:57:39 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:39 INFO : Bisync successful 2025/12/25 02:57:39 NOTICE: (27) : test resync 2025/12/25 02:57:39 NOTICE: (28) : bisync resync norm 2025/12/25 02:57:49 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:49 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:49 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:50 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:57:50 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:57:50 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:57:51 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:51 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:57:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:57:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/12/25 02:57:51 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:51 INFO : Copying Path2 files to Path1 2025/12/25 02:57:51 INFO : - Path2 Resync is copying files to - Path1 2025/12/25 02:57:51 INFO : - Path1 Resync is copying files to - Path2 2025/12/25 02:57:51 INFO : Resync updating listings 2025/12/25 02:57:52 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:52 INFO : Bisync successful 2025/12/25 02:57:52 NOTICE: (29) : test changed on one path 2025/12/25 02:57:52 NOTICE: (30) : touch-copy 2001-01-05 /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/ 2025/12/25 02:57:52 DEBUG : fs cache: switching user supplied name "/tmp/025543ne/datadir/test_normalization-moramiq7/" for canonical name "/tmp/025543ne/datadir/test_normalization-moramiq7" 2025/12/25 02:57:52 DEBUG : file1.txt: Set modification time 2001-01-05 2025/12/25 02:57:52 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" as "" 2025/12/25 02:57:52 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:52 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:57:53 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2" as "file1.txt" 2025/12/25 02:57:54 DEBUG : file1.txt: size = 19 OK 2025/12/25 02:57:54 DEBUG : file1.txt: Modification times differ by -48h0m0s: 2001-01-05 00:00:00 +0000 UTC, 2001-01-03 00:00:00 +0000 UTC 2025/12/25 02:57:54 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:55 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:57:55 DEBUG : file1.txt: Unchanged skipping 2025/12/25 02:57:55 NOTICE: (31) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2025/12/25 02:57:55 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:55 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:55 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" 2025/12/25 02:57:56 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" as "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:57:56 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: size = 19 OK 2025/12/25 02:57:56 DEBUG : file1.txt: Modification times differ by -72h0m0s: 2001-01-05 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC 2025/12/25 02:57:56 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:57:58 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:57:58 DEBUG : file1.txt: Unchanged skipping 2025/12/25 02:57:58 NOTICE: (32) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder éééö.txt 2025/12/25 02:57:58 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" as "éééö.txt" 2025/12/25 02:57:58 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:57:58 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" 2025/12/25 02:57:59 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/newfolder" as "éééö.txt" 2025/12/25 02:57:59 DEBUG : éééö.txt: size = 19 OK 2025/12/25 02:57:59 DEBUG : file1.txt: Modification times differ by -48h0m0s: 2001-01-05 00:00:00 +0000 UTC, 2001-01-03 00:00:00 +0000 UTC 2025/12/25 02:57:59 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:58:00 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:58:00 DEBUG : file1.txt: Unchanged skipping 2025/12/25 02:58:00 NOTICE: (33) : copy-as-NFC /tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder HeLlO,wOrLd!.txt 2025/12/25 02:58:00 DEBUG : copyFile "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" to "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:58:00 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_normalization-moramiq7/file1.txt" 2025/12/25 02:58:00 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/newfolder" 2025/12/25 02:58:02 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1/newfolder" as "HeLlO,wOrLd!.txt" 2025/12/25 02:58:02 DEBUG : HeLlO,wOrLd!.txt: size = 19 OK 2025/12/25 02:58:02 DEBUG : file1.txt: Modification times differ by -48h0m0s: 2001-01-05 00:00:00 +0000 UTC, 2001-01-03 00:00:00 +0000 UTC 2025/12/25 02:58:02 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/25 02:58:03 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:58:03 DEBUG : file1.txt: Unchanged skipping 2025/12/25 02:58:03 NOTICE: (34) : bisync norm 2025/12/25 02:58:12 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2025/12/25 02:58:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/12/25 02:58:12 DEBUG : pacer: Reducing sleep to 10ms 2025/12/25 02:58:13 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:58:13 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:13 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:58:15 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: size = 0 OK 2025/12/25 02:58:15 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:15 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2025/12/25 02:58:15 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:58:15 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/25 02:58:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/25 02:58:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/12/25 02:58:15 INFO : Synching Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" with Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:58:15 INFO : Building Path1 and Path2 listings 2025/12/25 02:58:15 INFO : Path1 checking for diffs 2025/12/25 02:58:15 INFO : - Path1 File changed: time (newer) - newfolder/HeLlO,wOrLd!.txt 2025/12/25 02:58:15 INFO : - Path1 File changed: time (newer) - newfolder/éééö.txt 2025/12/25 02:58:15 INFO : - Path1 File changed: time (newer) - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:58:15 INFO : Path1: 3 changes:  0 new,  3 modified,  0 deleted 2025/12/25 02:58:15 INFO : (Modified:  3 newer,  0 older) 2025/12/25 02:58:15 INFO : Path2 checking for diffs 2025/12/25 02:58:15 INFO : - Path2 File changed: time (newer) - file1.txt 2025/12/25 02:58:15 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/12/25 02:58:15 INFO : (Modified:  1 newer,  0 older) 2025/12/25 02:58:15 INFO : Applying changes 2025/12/25 02:58:15 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder/hello,WORLD!.txt 2025/12/25 02:58:15 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/newfolder/éééö.txt 2025/12/25 02:58:15 INFO : - Path1 Queue copy to Path2 - "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2025/12/25 02:58:15 INFO : - Path2 Queue copy to Path1 - TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/file1.txt 2025/12/25 02:58:15 INFO : - Path2 Do queued copies to - Path1 2025/12/25 02:58:17 INFO : file1.txt: Updated modification time in destination 2025/12/25 02:58:17 INFO : - Path1 Do queued copies to - Path2 2025/12/25 02:58:18 INFO : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Updated modification time in destination 2025/12/25 02:58:18 INFO : newfolder/HeLlO,wOrLd!.txt: Updated modification time in destination 2025/12/25 02:58:18 INFO : newfolder/éééö.txt: Updated modification time in destination 2025/12/25 02:58:18 INFO : Updating listings 2025/12/25 02:58:18 INFO : Validating listings for Path1 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path1/" vs Path2 "TestB2:rclone-test-qicarik1vute/025543ne/normalization/path2/" 2025/12/25 02:58:18 INFO : Bisync successful 2025/12/25 02:58:18 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:19 DEBUG : RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f409e9d4e533ed4c4_d20251225_m025642_c001_v7007000_t0000_u01766631402893") 2025/12/25 02:58:19 DEBUG : RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46be5_d20251225_m025551_c001_v0001116_t0001_u01766631351038") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41617d9fc663ceab_d20251225_m025813_c001_v7007000_t0000_u01766631493845") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46c33_d20251225_m025811_c001_v0001116_t0004_u01766631491999") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a93856100_d20251225_m025749_c001_v7007000_t0000_u01766631469263") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a13a4_d20251225_m025748_c001_v0001185_t0002_u01766631468039") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a938560af_d20251225_m025734_c001_v7007000_t0000_u01766631454429") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a1295_d20251225_m025733_c001_v0001185_t0035_u01766631453869") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f42064bfc4ff18582_d20251225_m025704_c001_v7007000_t0000_u01766631424948") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d40078_d20251225_m025703_c001_v0001173_t0010_u01766631423832") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41958158be46ca35_d20251225_m025637_c001_v7007000_t0000_u01766631397380") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46bef_d20251225_m025635_c001_v0001116_t0027_u01766631395630") 2025/12/25 02:58:19 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f417e999ea12b6e79_d20251225_m025606_c001_v7007000_t0000_u01766631366237") 2025/12/25 02:58:20 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0c73_d20251225_m025604_c001_v0001185_t0002_u01766631364989") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41617d9fc663ceac_d20251225_m025814_c001_v7007000_t0000_u01766631494026") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d40632_d20251225_m025813_c001_v0001173_t0017_u01766631493351") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a93856101_d20251225_m025749_c001_v7007000_t0000_u01766631469449") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46c28_d20251225_m025748_c001_v0001116_t0016_u01766631468790") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a938560b1_d20251225_m025734_c001_v7007000_t0000_u01766631454611") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a1299_d20251225_m025734_c001_v0001185_t0049_u01766631454243") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f42064bfc4ff18584_d20251225_m025705_c001_v7007000_t0000_u01766631425129") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a1088_d20251225_m025704_c001_v0001185_t0046_u01766631424755") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41958158be46ca37_d20251225_m025637_c001_v7007000_t0000_u01766631397561") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d3fe88_d20251225_m025637_c001_v0001173_t0003_u01766631397038") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f417e999ea12b6e7d_d20251225_m025606_c001_v7007000_t0000_u01766631366416") 2025/12/25 02:58:20 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46be9_d20251225_m025605_c001_v0001116_t0004_u01766631365770") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f109db5b04603701f_d20251225_m025816_c001_v0001154_t0020_u01766631496925") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1177af01a44fd721_d20251225_m025738_c001_v0001157_t0016_u01766631458780") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f115890cdf7d2202c_d20251225_m025707_c001_v0001131_t0018_u01766631427245") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f409e9d4e533ed4c5_d20251225_m025643_c001_v7007000_t0000_u01766631403076") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10276b238bbc4f4e_d20251225_m025640_c001_v0001185_t0011_u01766631400427") 2025/12/25 02:58:20 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0b6f_d20251225_m025550_c001_v0001185_t0026_u01766631350809") 2025/12/25 02:58:20 DEBUG : file2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108aae915e8e72d3_d20251225_m025707_c001_v0001145_t0049_u01766631427554") 2025/12/25 02:58:21 DEBUG : file3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1181392669f6cacf_d20251225_m025654_c001_v0001173_t0011_u01766631414951") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f40505da8d98c1159_d20251225_m025807_c001_v7007000_t0000_u01766631487258") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f106fa333df7833ae_d20251225_m025806_c001_v0001185_t0002_u01766631486033") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a14c8_d20251225_m025803_c001_v0001185_t0059_u01766631483917") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a938560f6_d20251225_m025743_c001_v7007000_t0000_u01766631463523") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f103557bc2644212a_d20251225_m025741_c001_v0001106_t0046_u01766631461814") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a1316_d20251225_m025740_c001_v0001185_t0040_u01766631460407") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a93856090_d20251225_m025729_c001_v7007000_t0000_u01766631449630") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1105bfc953ab4336_d20251225_m025728_c001_v0001093_t0059_u01766631448162") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d40271_d20251225_m025726_c001_v0001173_t0036_u01766631446039") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f42064bfc4ff1856d_d20251225_m025659_c001_v7007000_t0000_u01766631419555") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1135f0640bf32c7c_d20251225_m025658_c001_v0001173_t0058_u01766631418232") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46bf6_d20251225_m025655_c001_v0001116_t0045_u01766631415823") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f415b2f92af4a0615_d20251225_m025630_c001_v7007000_t0000_u01766631390502") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10276b238bbc4e73_d20251225_m025629_c001_v0001185_t0024_u01766631389236") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0dfe_d20251225_m025627_c001_v0001185_t0035_u01766631387074") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f417e999ea12b6e45_d20251225_m025600_c001_v7007000_t0000_u01766631360380") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1045296638a4e6f5_d20251225_m025559_c001_v0001185_t0021_u01766631359193") 2025/12/25 02:58:21 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0bda_d20251225_m025557_c001_v0001185_t0010_u01766631357003") 2025/12/25 02:58:21 DEBUG : newfolder/HeLlO,wOrLd!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f115b2f30a2c209fa_d20251225_m025803_c001_v0001171_t0043_u01766631483246") 2025/12/25 02:58:22 DEBUG : newfolder/HeLlO,wOrLd!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11436a8e7cf67acf_d20251225_m025739_c001_v0001173_t0013_u01766631459070") 2025/12/25 02:58:22 DEBUG : newfolder/HeLlO,wOrLd!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d401b7_d20251225_m025716_c001_v0001173_t0004_u01766631436892") 2025/12/25 02:58:22 DEBUG : newfolder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10592de8cd349793_d20251225_m025800_c001_v0001173_t0039_u01766631480425") 2025/12/25 02:58:22 DEBUG : newfolder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f116ba38487509d40_d20251225_m025738_c001_v0001041_t0057_u01766631458962") 2025/12/25 02:58:22 DEBUG : newfolder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1108fd89e1629a65_d20251225_m025714_c001_v0001185_t0017_u01766631434123") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41617d9fc663ceb2_d20251225_m025815_c001_v7007000_t0000_u01766631495167") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a15a3_d20251225_m025814_c001_v0001185_t0003_u01766631494591") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a93856109_d20251225_m025750_c001_v7007000_t0000_u01766631470767") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d40455_d20251225_m025749_c001_v0001173_t0020_u01766631469995") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a938560bf_d20251225_m025736_c001_v7007000_t0000_u01766631456739") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46c1e_d20251225_m025735_c001_v0001116_t0023_u01766631455199") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f42064bfc4ff1858a_d20251225_m025706_c001_v7007000_t0000_u01766631426636") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a109b_d20251225_m025705_c001_v0001185_t0008_u01766631425366") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41958158be46ca40_d20251225_m025638_c001_v7007000_t0000_u01766631398720") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0ea8_d20251225_m025638_c001_v0001185_t0026_u01766631398126") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f417e999ea12b6e88_d20251225_m025607_c001_v7007000_t0000_u01766631367703") 2025/12/25 02:58:22 DEBUG : ě áñhࢺ_測試Рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d3fc22_d20251225_m025606_c001_v0001173_t0045_u01766631366988") 2025/12/25 02:58:22 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41617d9fc663ceb4_d20251225_m025815_c001_v7007000_t0000_u01766631495350") 2025/12/25 02:58:22 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a15b2_d20251225_m025814_c001_v0001185_t0004_u01766631494985") 2025/12/25 02:58:22 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a9385610b_d20251225_m025750_c001_v7007000_t0000_u01766631470950") 2025/12/25 02:58:22 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a13dc_d20251225_m025750_c001_v0001185_t0015_u01766631470532") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4036db8a938560c3_d20251225_m025736_c001_v7007000_t0000_u01766631456923") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d4032f_d20251225_m025736_c001_v0001173_t0004_u01766631456303") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f42064bfc4ff1858b_d20251225_m025706_c001_v7007000_t0000_u01766631426816") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114c0a3d72a46bfb_d20251225_m025706_c001_v0001116_t0050_u01766631426131") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f41958158be46ca41_d20251225_m025638_c001_v7007000_t0000_u01766631398901") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0eb1_d20251225_m025638_c001_v0001185_t0017_u01766631398528") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f417e999ea12b6e89_d20251225_m025607_c001_v7007000_t0000_u01766631367883") 2025/12/25 02:58:23 DEBUG : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f11961597050a0ca6_d20251225_m025607_c001_v0001185_t0000_u01766631367485") 2025/12/25 02:58:23 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1132fe9c78e99cf6_d20251225_m025757_c001_v0001165_t0025_u01766631477861") 2025/12/25 02:58:23 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f113657aa3344b903_d20251225_m025711_c001_v0001093_t0025_u01766631431249") 2025/12/25 02:58:23 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/normalization/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:24 DEBUG : RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f401ef7717c4be5dc_d20251225_m025644_c001_v7007000_t0000_u01766631404592") 2025/12/25 02:58:24 DEBUG : RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f106b4c1be1bcd96c_d20251225_m025553_c001_v0001167_t0051_u01766631353304") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f102f9da3ab613cd8_d20251225_m025755_c001_v0001173_t0048_u01766631475070") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108e24f3edac5431_d20251225_m025719_c001_v0001172_t0010_u01766631439427") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1045296638a4eaa4_d20251225_m025649_c001_v0001185_t0037_u01766631409075") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f401ef7717c4be5e0_d20251225_m025644_c001_v7007000_t0000_u01766631404776") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f116ab34f29a3c0fd_d20251225_m025620_c001_v0001173_t0058_u01766631380473") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f107fd83aa0000bac_d20251225_m025608_c001_v0001185_t0049_u01766631368869") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f107d352af2e69412_d20251225_m025556_c001_v0001173_t0042_u01766631356636") 2025/12/25 02:58:24 DEBUG : file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108d54f537a44fd7_d20251225_m025552_c001_v0001185_t0006_u01766631352937") 2025/12/25 02:58:24 DEBUG : file2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f117d55624a640980_d20251225_m025651_c001_v0001173_t0036_u01766631411905") 2025/12/25 02:58:24 DEBUG : file3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1079d8f5bf0da166_d20251225_m025708_c001_v0001099_t0008_u01766631428218") 2025/12/25 02:58:24 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f415b2f92af4a071c_d20251225_m025811_c001_v7007000_t0000_u01766631491451") 2025/12/25 02:58:24 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f104e104d16f4833e_d20251225_m025810_c001_v0001183_t0018_u01766631490153") 2025/12/25 02:58:24 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04f706_d20251225_m025807_c001_v0001173_t0025_u01766631487806") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f415b2f92af4a070e_d20251225_m025747_c001_v7007000_t0000_u01766631467474") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f100eb1119a6a760a_d20251225_m025746_c001_v0001185_t0043_u01766631466284") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108d54f537a45d87_d20251225_m025744_c001_v0001185_t0058_u01766631464097") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f422c02464afc6248_d20251225_m025733_c001_v7007000_t0000_u01766631453310") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014f96c02b1861f_d20251225_m025732_c001_v0001120_t0022_u01766631452035") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1135f0640bf32fe5_d20251225_m025730_c001_v0001173_t0043_u01766631450173") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f40284e022be9dd91_d20251225_m025703_c001_v7007000_t0000_u01766631423282") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10610e95636fcaeb_d20251225_m025702_c001_v0001165_t0002_u01766631422036") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f106b4c1be1bcdda7_d20251225_m025700_c001_v0001167_t0015_u01766631420118") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f40284e022be9dcb4_d20251225_m025635_c001_v7007000_t0000_u01766631395011") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108a12335c5f610e_d20251225_m025633_c001_v0001167_t0008_u01766631393572") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04efe9_d20251225_m025631_c001_v0001173_t0022_u01766631391063") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f4217ec34eeb9f257_d20251225_m025604_c001_v7007000_t0000_u01766631364447") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f115b0c97e4f9c6b8_d20251225_m025603_c001_v0001173_t0053_u01766631363111") 2025/12/25 02:58:25 DEBUG : modtime_write_test: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108d54f537a450c5_d20251225_m025600_c001_v0001185_t0049_u01766631360950") 2025/12/25 02:58:25 DEBUG : newfolder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1171cfbf5b6ae1cd_d20251225_m025818_c001_v0001091_t0036_u01766631498525") 2025/12/25 02:58:25 DEBUG : newfolder/éééö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108e924b9204a383_d20251225_m025722_c001_v0001173_t0048_u01766631442283") 2025/12/25 02:58:25 DEBUG : newfolder/hello,WORLD!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f104a556ee714dcb9_d20251225_m025818_c001_v0001173_t0038_u01766631498297") 2025/12/25 02:58:25 DEBUG : newfolder/hello,WORLD!.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1181392669f6cd09_d20251225_m025725_c001_v0001173_t0019_u01766631445273") 2025/12/25 02:58:25 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f105f3ce2ccfb0302_d20251225_m025818_c001_v0001185_t0039_u01766631498251") 2025/12/25 02:58:26 DEBUG : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1098663c8a9c2223_d20251225_m025739_c001_v0001185_t0015_u01766631459863") bisync_test.go:608: TEST normalization PASSED === RUN TestBisyncRemoteRemote/rmdirs 2025/12/25 02:58:26 INFO : path1: Making directory 2025/12/25 02:58:26 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/rmdirs/path1" 2025/12/25 02:58:27 INFO : path2: Making directory 2025/12/25 02:58:27 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/rmdirs/path2" 2025/12/25 02:58:28 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial" 2025/12/25 02:58:28 DEBUG : Creating backend with remote "/tmp/025543ne/initdir/test_rmdirs-kebodez0" 2025/12/25 02:58:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : subdir: Making directory with metadata 2025/12/25 02:58:28 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/12/25 02:58:28 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/12/25 02:58:28 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : Local file system at /tmp/025543ne/initdir/test_rmdirs-kebodez0: Waiting for checks to finish 2025/12/25 02:58:28 DEBUG : Local file system at /tmp/025543ne/initdir/test_rmdirs-kebodez0: Waiting for transfers to finish 2025/12/25 02:58:28 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/12/25 02:58:28 INFO : file1.copy1.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/12/25 02:58:28 INFO : file1.copy2.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/12/25 02:58:28 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/12/25 02:58:28 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/12/25 02:58:28 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/12/25 02:58:28 INFO : file1.copy4.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/12/25 02:58:28 INFO : file1.copy5.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/12/25 02:58:28 INFO : file1.copy3.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2025/12/25 02:58:28 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:28 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/12/25 02:58:28 INFO : file1.txt: Copied (new) 2025/12/25 02:58:28 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/12/25 02:58:28 INFO : subdir/file20.txt: Copied (new) 2025/12/25 02:58:28 INFO : subdir: Set directory modification time (using SetModTime) 2025/12/25 02:58:28 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles" 2025/12/25 02:58:28 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_rmdirs-raribaz4" 2025/12/25 02:58:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:28 DEBUG : Local file system at /tmp/025543ne/datadir/test_rmdirs-raribaz4: Waiting for checks to finish 2025/12/25 02:58:28 DEBUG : Local file system at /tmp/025543ne/datadir/test_rmdirs-raribaz4: Waiting for transfers to finish 2025/12/25 02:58:28 DEBUG : file1.txt.f41fd5ee.partial: size = 19 OK 2025/12/25 02:58:28 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:28 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt 2025/12/25 02:58:28 INFO : file1.txt: Copied (new) 2025/12/25 02:58:28 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:29 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:30 NOTICE: checking initFs Local file system at /tmp/025543ne/initdir/test_rmdirs-kebodez0 2025/12/25 02:58:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 INFO : subdir: Making directory 2025/12/25 02:58:30 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:30 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path1: Waiting for checks to finish 2025/12/25 02:58:30 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path1: Waiting for transfers to finish 2025/12/25 02:58:31 DEBUG : file1.copy1.txt: size = 0 OK 2025/12/25 02:58:31 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:31 INFO : file1.copy1.txt: Copied (new) 2025/12/25 02:58:31 DEBUG : file1.copy4.txt: size = 0 OK 2025/12/25 02:58:31 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:31 INFO : file1.copy4.txt: Copied (new) 2025/12/25 02:58:32 DEBUG : file1.copy3.txt: size = 0 OK 2025/12/25 02:58:32 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:32 INFO : file1.copy3.txt: Copied (new) 2025/12/25 02:58:32 DEBUG : file1.copy2.txt: size = 0 OK 2025/12/25 02:58:32 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:32 INFO : file1.copy2.txt: Copied (new) 2025/12/25 02:58:32 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:58:32 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:58:32 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:32 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:58:32 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:32 INFO : file1.txt: Copied (new) 2025/12/25 02:58:32 DEBUG : file1.copy5.txt: size = 0 OK 2025/12/25 02:58:32 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:32 INFO : file1.copy5.txt: Copied (new) 2025/12/25 02:58:32 DEBUG : subdir/file20.txt: size = 0 OK 2025/12/25 02:58:32 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:32 INFO : subdir/file20.txt: Copied (new) 2025/12/25 02:58:32 NOTICE: checking Path1 B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path1 2025/12/25 02:58:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 INFO : subdir: Making directory 2025/12/25 02:58:32 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:32 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path2: Waiting for checks to finish 2025/12/25 02:58:32 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path2: Waiting for transfers to finish 2025/12/25 02:58:33 DEBUG : file1.copy1.txt: size = 0 OK 2025/12/25 02:58:33 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:33 INFO : file1.copy1.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : file1.copy4.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : file1.copy4.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : file1.copy2.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : file1.copy2.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : file1.copy3.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : file1.copy3.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:58:34 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:58:34 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:34 DEBUG : file1.copy5.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : file1.copy5.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : subdir/file20.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : subdir/file20.txt: Copied (new) 2025/12/25 02:58:34 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:58:34 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:34 INFO : file1.txt: Copied (new) 2025/12/25 02:58:34 NOTICE: checking path2 B2 bucket rclone-test-qicarik1vute path 025543ne/rmdirs/path2 2025/12/25 02:58:35 NOTICE: (01) : test rmdirs 2025/12/25 02:58:35 NOTICE: (02) : test initial bisync 2025/12/25 02:58:35 NOTICE: (03) : bisync resync bisync_test.go:1007: skipping test as remote does not support empty dirs === RUN TestBisyncRemoteRemote/volatile 2025/12/25 02:58:35 INFO : path1: Making directory 2025/12/25 02:58:35 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/volatile/path1" 2025/12/25 02:58:36 INFO : path2: Making directory 2025/12/25 02:58:36 DEBUG : Creating backend with remote "TestB2:rclone-test-qicarik1vute/025543ne/volatile/path2" 2025/12/25 02:58:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial" 2025/12/25 02:58:37 DEBUG : Creating backend with remote "/tmp/025543ne/initdir/test_volatile-nugexuy0" 2025/12/25 02:58:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/12/25 02:58:37 INFO : file1.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : Local file system at /tmp/025543ne/initdir/test_volatile-nugexuy0: Waiting for checks to finish 2025/12/25 02:58:37 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : Local file system at /tmp/025543ne/initdir/test_volatile-nugexuy0: Waiting for transfers to finish 2025/12/25 02:58:37 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/12/25 02:58:37 INFO : file2.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/12/25 02:58:37 INFO : file4.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/12/25 02:58:37 INFO : file3.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/12/25 02:58:37 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/12/25 02:58:37 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/12/25 02:58:37 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:37 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/12/25 02:58:37 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 INFO : file6.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/12/25 02:58:37 INFO : file7.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/12/25 02:58:37 INFO : file8.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/12/25 02:58:37 INFO : file5.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles" 2025/12/25 02:58:37 DEBUG : Creating backend with remote "/tmp/025543ne/datadir/test_volatile-vamuwus3" 2025/12/25 02:58:37 DEBUG : dummy.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file10.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file11.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:37 DEBUG : Local file system at /tmp/025543ne/datadir/test_volatile-vamuwus3: Waiting for checks to finish 2025/12/25 02:58:37 DEBUG : dummy.txt.b3782999.partial: size = 0 OK 2025/12/25 02:58:37 DEBUG : Local file system at /tmp/025543ne/datadir/test_volatile-vamuwus3: Waiting for transfers to finish 2025/12/25 02:58:37 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/25 02:58:37 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt 2025/12/25 02:58:37 INFO : dummy.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file1.txt.be52b1c9.partial: size = 19 OK 2025/12/25 02:58:37 DEBUG : file11.txt.d4a7309d.partial: size = 19 OK 2025/12/25 02:58:37 DEBUG : file10.txt.bdde3bf8.partial: size = 19 OK 2025/12/25 02:58:37 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:37 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:37 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt 2025/12/25 02:58:37 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt 2025/12/25 02:58:37 INFO : file10.txt: Copied (new) 2025/12/25 02:58:37 INFO : file1.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:37 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt 2025/12/25 02:58:37 DEBUG : file2.txt.23ff1769.partial: size = 13 OK 2025/12/25 02:58:37 INFO : file11.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2025/12/25 02:58:37 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt 2025/12/25 02:58:37 INFO : file2.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK 2025/12/25 02:58:37 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2025/12/25 02:58:37 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2025/12/25 02:58:37 INFO : file5R.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file6.txt.7b4c87b3.partial: size = 19 OK 2025/12/25 02:58:37 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK 2025/12/25 02:58:37 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:37 DEBUG : file7.txt.38c43fbf.partial: size = 19 OK 2025/12/25 02:58:37 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2025/12/25 02:58:37 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt 2025/12/25 02:58:37 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/25 02:58:37 INFO : file6.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2025/12/25 02:58:37 INFO : file5L.txt: Copied (new) 2025/12/25 02:58:37 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt 2025/12/25 02:58:37 INFO : file7.txt: Copied (new) 2025/12/25 02:58:37 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path1: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:38 INFO : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path2: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:39 NOTICE: checking initFs Local file system at /tmp/025543ne/initdir/test_volatile-nugexuy0 2025/12/25 02:58:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:39 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path1: Waiting for checks to finish 2025/12/25 02:58:39 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path1: Waiting for transfers to finish 2025/12/25 02:58:40 DEBUG : file2.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file2.txt: Copied (new) 2025/12/25 02:58:40 DEBUG : file3.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file3.txt: Copied (new) 2025/12/25 02:58:40 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:58:40 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:58:40 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:40 DEBUG : file4.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file4.txt: Copied (new) 2025/12/25 02:58:40 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file1.txt: Copied (new) 2025/12/25 02:58:40 DEBUG : file6.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file6.txt: Copied (new) 2025/12/25 02:58:40 DEBUG : file5.txt: size = 0 OK 2025/12/25 02:58:40 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:40 INFO : file5.txt: Copied (new) 2025/12/25 02:58:41 DEBUG : file7.txt: size = 0 OK 2025/12/25 02:58:41 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:41 INFO : file7.txt: Copied (new) 2025/12/25 02:58:41 DEBUG : file8.txt: size = 0 OK 2025/12/25 02:58:41 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:41 INFO : file8.txt: Copied (new) 2025/12/25 02:58:41 NOTICE: checking Path1 B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path1 2025/12/25 02:58:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/12/25 02:58:41 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path2: Waiting for checks to finish 2025/12/25 02:58:41 DEBUG : B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path2: Waiting for transfers to finish 2025/12/25 02:58:42 DEBUG : file2.txt: size = 0 OK 2025/12/25 02:58:42 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:42 INFO : file2.txt: Copied (new) 2025/12/25 02:58:42 DEBUG : file1.txt: size = 0 OK 2025/12/25 02:58:42 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:42 INFO : file1.txt: Copied (new) 2025/12/25 02:58:42 DEBUG : file4.txt: size = 0 OK 2025/12/25 02:58:42 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:42 INFO : file4.txt: Copied (new) 2025/12/25 02:58:42 DEBUG : file6.txt: size = 0 OK 2025/12/25 02:58:42 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:42 INFO : file6.txt: Copied (new) 2025/12/25 02:58:42 DEBUG : file5.txt: size = 0 OK 2025/12/25 02:58:42 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:42 INFO : file5.txt: Copied (new) 2025/12/25 02:58:43 DEBUG : file7.txt: size = 0 OK 2025/12/25 02:58:43 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:43 INFO : file7.txt: Copied (new) 2025/12/25 02:58:43 DEBUG : file8.txt: size = 0 OK 2025/12/25 02:58:43 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:43 INFO : file8.txt: Copied (new) 2025/12/25 02:58:43 DEBUG : file3.txt: size = 0 OK 2025/12/25 02:58:43 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/25 02:58:43 INFO : file3.txt: Copied (new) 2025/12/25 02:58:43 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/25 02:58:43 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/25 02:58:43 INFO : RCLONE_TEST: Copied (new) 2025/12/25 02:58:43 NOTICE: checking path2 B2 bucket rclone-test-qicarik1vute path 025543ne/volatile/path2 2025/12/25 02:58:43 NOTICE: (01) : test volatile 2025/12/25 02:58:43 NOTICE: (02) : test initial bisync 2025/12/25 02:58:43 NOTICE: (03) : bisync resync bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files 2025/12/25 02:58:43 DEBUG : B2 bucket rclone-test-qicarik1vute: Purge remote 2025/12/25 02:58:43 INFO : B2 bucket rclone-test-qicarik1vute: cleaning bucket "rclone-test-qicarik1vute" of all files 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f108a12335c5f6935_d20251225_m025831_c001_v0001167_t0009_u01766631511776") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04f8ad_d20251225_m025831_c001_v0001173_t0032_u01766631511362") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f104b903bf912418d_d20251225_m025831_c001_v0001144_t0033_u01766631511576") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1126f6e25804310b_d20251225_m025831_c001_v0001185_t0003_u01766631511910") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04f8ba_d20251225_m025831_c001_v0001173_t0044_u01766631511722") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f119a28e6cd04f8c2_d20251225_m025832_c001_v0001173_t0033_u01766631512085") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1126f6e258043110_d20251225_m025832_c001_v0001185_t0055_u01766631512097") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f104b903bf912418f_d20251225_m025832_c001_v0001144_t0019_u01766631512144") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f104b1dbbce399399_d20251225_m025834_c001_v0001167_t0032_u01766631514190") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f112f80d112f7a8ca_d20251225_m025833_c001_v0001102_t0055_u01766631513642") 2025/12/25 02:58:44 DEBUG : 025543ne/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1104c9ae6829c3d4_d20251225_m025833_c001_v0001173_t0047_u01766631513832") 2025/12/25 02:58:45 DEBUG : 025543ne/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f118954637301d6e8_d20251225_m025834_c001_v0001167_t0016_u01766631514006") 2025/12/25 02:58:45 DEBUG : 025543ne/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f112f80d112f7a8ce_d20251225_m025834_c001_v0001102_t0003_u01766631514018") 2025/12/25 02:58:45 DEBUG : 025543ne/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f112f80d112f7a8d2_d20251225_m025834_c001_v0001102_t0025_u01766631514329") 2025/12/25 02:58:45 DEBUG : 025543ne/rmdirs/path2/file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1104c9ae6829c3f0_d20251225_m025834_c001_v0001173_t0008_u01766631514418") 2025/12/25 02:58:45 DEBUG : 025543ne/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f118954637301d6f4_d20251225_m025834_c001_v0001167_t0028_u01766631514547") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114878fd128ad1bf_d20251225_m025840_c001_v0001178_t0015_u01766631520529") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1174731b54d34214_d20251225_m025840_c001_v0001037_t0011_u01766631520180") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1118f554076f9145_d20251225_m025839_c001_v0001173_t0029_u01766631519939") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d4087e_d20251225_m025840_c001_v0001173_t0049_u01766631520294") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file4.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1118f554076f9147_d20251225_m025840_c001_v0001173_t0025_u01766631520446") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file5.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1014b30dd5d40883_d20251225_m025840_c001_v0001173_t0005_u01766631520649") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file6.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f114878fd128ad1c1_d20251225_m025840_c001_v0001178_t0005_u01766631520753") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file7.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1118f554076f9150_d20251225_m025840_c001_v0001173_t0015_u01766631520786") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path1/file8.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1174731b54d34216_d20251225_m025840_c001_v0001037_t0044_u01766631520948") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/RCLONE_TEST: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f111b3a1da7a6103c_d20251225_m025842_c001_v0001109_t0058_u01766631522784") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file1.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1104c9ae6829c4a2_d20251225_m025842_c001_v0001173_t0039_u01766631522217") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file2.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1108fd89e162a21a_d20251225_m025842_c001_v0001185_t0014_u01766631522360") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file3.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f10973f1971999a9b_d20251225_m025842_c001_v0001167_t0031_u01766631522586") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file4.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1108fd89e162a224_d20251225_m025842_c001_v0001185_t0047_u01766631522547") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file5.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1104c9ae6829c4ac_d20251225_m025842_c001_v0001173_t0029_u01766631522559") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file6.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1108fd89e162a228_d20251225_m025842_c001_v0001185_t0054_u01766631522733") 2025/12/25 02:58:45 DEBUG : 025543ne/volatile/path2/file7.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1108fd89e162a22c_d20251225_m025842_c001_v0001185_t0004_u01766631522934") 2025/12/25 02:58:46 DEBUG : 025543ne/volatile/path2/file8.txt: Deleting (id "4_z7fe20dd360fd5b3b9fb80e1b_f1104c9ae6829c4b7_d20251225_m025843_c001_v0001173_t0054_u01766631523001") --- PASS: TestBisyncRemoteRemote (183.40s) --- PASS: TestBisyncRemoteRemote/normalization (162.52s) --- SKIP: TestBisyncRemoteRemote/rmdirs (8.56s) --- SKIP: TestBisyncRemoteRemote/volatile (8.52s) PASS 2025/12/25 02:58:46 DEBUG : B2 bucket rclone-test-wadunib0faji: Purge remote 2025/12/25 02:58:46 INFO : B2 bucket rclone-test-wadunib0faji: cleaning bucket "rclone-test-wadunib0faji" of all files 2025/12/25 02:58:47 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(normalization|rmdirs|volatile)$'" - Finished OK in 3m4.720263992s (try 2/5)