"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^resync$'" - Starting (try 3/5) 2025/12/30 02:57:55 DEBUG : Creating backend with remote "TestB2:rclone-test-xuyiwij2yupa" 2025/12/30 02:57:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/30 02:57:55 DEBUG : Creating backend with remote "/tmp/rclone3710780398" === RUN TestBisyncRemoteRemote 2025/12/30 02:57:55 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo" 2025/12/30 02:57:56 NOTICE: remote: TestB2:rclone-test-tatezox3wemo === RUN TestBisyncRemoteRemote/resync 2025/12/30 02:57:56 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi" 2025/12/30 02:57:57 INFO : path1: Making directory 2025/12/30 02:57:58 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1" 2025/12/30 02:57:59 INFO : path2: Making directory 2025/12/30 02:57:59 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2" 2025/12/30 02:58:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial" 2025/12/30 02:58:00 DEBUG : Creating backend with remote "/tmp/025756pi/initdir/test_resync-gofivog7" 2025/12/30 02:58:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/12/30 02:58:00 INFO : file1.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/12/30 02:58:00 INFO : file2.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/12/30 02:58:00 INFO : file3.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/12/30 02:58:00 INFO : file4.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/12/30 02:58:00 INFO : file5.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/12/30 02:58:00 INFO : file6.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2025/12/30 02:58:00 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/12/30 02:58:00 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/12/30 02:58:00 INFO : file7.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : Local file system at /tmp/025756pi/initdir/test_resync-gofivog7: Waiting for checks to finish 2025/12/30 02:58:00 DEBUG : Local file system at /tmp/025756pi/initdir/test_resync-gofivog7: Waiting for transfers to finish 2025/12/30 02:58:00 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/12/30 02:58:00 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/12/30 02:58:00 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/12/30 02:58:00 INFO : RCLONE_TEST: Copied (new) 2025/12/30 02:58:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles" 2025/12/30 02:58:00 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0" 2025/12/30 02:58:00 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:00 DEBUG : Local file system at /tmp/025756pi/datadir/test_resync-casosip0: Waiting for checks to finish 2025/12/30 02:58:00 DEBUG : Local file system at /tmp/025756pi/datadir/test_resync-casosip0: Waiting for transfers to finish 2025/12/30 02:58:00 DEBUG : fileB.txt.b8ec97e6.partial: size = 19 OK 2025/12/30 02:58:00 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/30 02:58:00 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt 2025/12/30 02:58:00 INFO : fileB.txt: Copied (new) 2025/12/30 02:58:00 DEBUG : fileA.txt.04c13af3.partial: size = 19 OK 2025/12/30 02:58:00 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/12/30 02:58:00 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt 2025/12/30 02:58:00 INFO : fileA.txt: Copied (new) 2025/12/30 02:58:00 INFO : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: cleaning bucket "rclone-test-tatezox3wemo" of all files 2025/12/30 02:58:01 INFO : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: cleaning bucket "rclone-test-tatezox3wemo" of all files 2025/12/30 02:58:02 NOTICE: checking initFs Local file system at /tmp/025756pi/initdir/test_resync-gofivog7 2025/12/30 02:58:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:02 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Waiting for checks to finish 2025/12/30 02:58:02 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Waiting for transfers to finish 2025/12/30 02:58:03 DEBUG : file2.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file2.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file4.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file4.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file1.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file1.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file3.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file3.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file5.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file5.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file7.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file7.txt: Copied (new) 2025/12/30 02:58:03 DEBUG : file6.txt: size = 0 OK 2025/12/30 02:58:03 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:03 INFO : file6.txt: Copied (new) 2025/12/30 02:58:04 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/30 02:58:04 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/30 02:58:04 INFO : RCLONE_TEST: Copied (new) 2025/12/30 02:58:04 NOTICE: checking Path1 B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1 2025/12/30 02:58:04 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/12/30 02:58:04 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Waiting for checks to finish 2025/12/30 02:58:04 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Waiting for transfers to finish 2025/12/30 02:58:06 DEBUG : file1.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file1.txt: Copied (new) 2025/12/30 02:58:06 DEBUG : file2.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file2.txt: Copied (new) 2025/12/30 02:58:06 DEBUG : file3.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file3.txt: Copied (new) 2025/12/30 02:58:06 DEBUG : file6.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file6.txt: Copied (new) 2025/12/30 02:58:06 DEBUG : file5.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file5.txt: Copied (new) 2025/12/30 02:58:06 DEBUG : RCLONE_TEST: size = 109 OK 2025/12/30 02:58:06 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/12/30 02:58:06 INFO : RCLONE_TEST: Copied (new) 2025/12/30 02:58:06 DEBUG : file7.txt: size = 0 OK 2025/12/30 02:58:06 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:06 INFO : file7.txt: Copied (new) 2025/12/30 02:58:07 DEBUG : file4.txt: size = 0 OK 2025/12/30 02:58:07 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/12/30 02:58:07 INFO : file4.txt: Copied (new) 2025/12/30 02:58:07 NOTICE: checking path2 B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2 2025/12/30 02:58:07 NOTICE: (01) : test resync 2025/12/30 02:58:07 NOTICE: (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2025/12/30 02:58:07 NOTICE: (03) : purge-children TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/ 2025/12/30 02:58:07 DEBUG : RCLONE_TEST: Remove file 2025/12/30 02:58:07 DEBUG : file1.txt: Remove file 2025/12/30 02:58:07 DEBUG : file2.txt: Remove file 2025/12/30 02:58:07 DEBUG : file3.txt: Remove file 2025/12/30 02:58:08 DEBUG : file4.txt: Remove file 2025/12/30 02:58:08 DEBUG : file5.txt: Remove file 2025/12/30 02:58:08 DEBUG : file6.txt: Remove file 2025/12/30 02:58:08 DEBUG : file7.txt: Remove file 2025/12/30 02:58:08 NOTICE: (04) : bisync resync 2025/12/30 02:58:17 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:58:17 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:58:17 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/30 02:58:17 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/30 02:58:17 INFO : Synching Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" with Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:17 INFO : Copying Path2 files to Path1 2025/12/30 02:58:17 INFO : - Path2 Resync is copying files to - Path1 2025/12/30 02:58:18 INFO : RCLONE_TEST: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file3.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file2.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file1.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file6.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file4.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file5.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : file7.txt: Copied (server-side copy) 2025/12/30 02:58:19 INFO : - Path1 Resync is copying files to - Path2 2025/12/30 02:58:20 INFO : Resync updating listings 2025/12/30 02:58:20 INFO : Validating listings for Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" vs Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:20 INFO : Bisync successful 2025/12/30 02:58:20 NOTICE: (05) : move-listings empty-path1 2025/12/30 02:58:20 NOTICE: (06) : test 2. resync with empty path2, resulting in syncing all content to path2. 2025/12/30 02:58:20 NOTICE: (07) : purge-children TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ 2025/12/30 02:58:20 DEBUG : RCLONE_TEST: Remove file 2025/12/30 02:58:20 DEBUG : file1.txt: Remove file 2025/12/30 02:58:20 DEBUG : file2.txt: Remove file 2025/12/30 02:58:20 DEBUG : file3.txt: Remove file 2025/12/30 02:58:21 DEBUG : file4.txt: Remove file 2025/12/30 02:58:21 DEBUG : file5.txt: Remove file 2025/12/30 02:58:21 DEBUG : file6.txt: Remove file 2025/12/30 02:58:21 DEBUG : file7.txt: Remove file 2025/12/30 02:58:21 NOTICE: (08) : bisync resync 2025/12/30 02:58:30 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:58:30 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:58:30 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/30 02:58:30 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/30 02:58:30 INFO : Synching Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" with Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:30 INFO : Copying Path2 files to Path1 2025/12/30 02:58:30 INFO : - Path2 Resync is copying files to - Path1 2025/12/30 02:58:30 INFO : There was nothing to transfer 2025/12/30 02:58:30 INFO : - Path1 Resync is copying files to - Path2 2025/12/30 02:58:31 INFO : RCLONE_TEST: Copied (server-side copy) 2025/12/30 02:58:31 INFO : file2.txt: Copied (server-side copy) 2025/12/30 02:58:31 INFO : file1.txt: Copied (server-side copy) 2025/12/30 02:58:31 INFO : file6.txt: Copied (server-side copy) 2025/12/30 02:58:32 INFO : file3.txt: Copied (server-side copy) 2025/12/30 02:58:32 INFO : file5.txt: Copied (server-side copy) 2025/12/30 02:58:32 INFO : file7.txt: Copied (server-side copy) 2025/12/30 02:58:32 INFO : file4.txt: Copied (server-side copy) 2025/12/30 02:58:32 INFO : Resync updating listings 2025/12/30 02:58:32 INFO : Validating listings for Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" vs Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:32 INFO : Bisync successful 2025/12/30 02:58:32 NOTICE: (09) : move-listings empty-path2 2025/12/30 02:58:32 NOTICE: (10) : test 3. exercise all of the various file difference scenarios during a resync. 2025/12/30 02:58:32 NOTICE: (11) : touch-glob 2002-02-02 /tmp/025756pi/datadir/test_resync-casosip0/ fileA.txt 2025/12/30 02:58:32 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/" 2025/12/30 02:58:32 DEBUG : fs cache: renaming cache item "/tmp/025756pi/datadir/test_resync-casosip0/" to be canonical "/tmp/025756pi/datadir/test_resync-casosip0" 2025/12/30 02:58:32 DEBUG : fileA.txt: Set modification time 2002-02-02 2025/12/30 02:58:32 NOTICE: (12) : touch-glob 1999-09-09 /tmp/025756pi/datadir/test_resync-casosip0/ fileB.txt 2025/12/30 02:58:32 DEBUG : fs cache: switching user supplied name "/tmp/025756pi/datadir/test_resync-casosip0/" for canonical name "/tmp/025756pi/datadir/test_resync-casosip0" 2025/12/30 02:58:32 DEBUG : fileB.txt: Set modification time 1999-09-09 2025/12/30 02:58:32 NOTICE: (13) : test = file - path1 - path2 - expected action - who wins 2025/12/30 02:58:32 NOTICE: (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2025/12/30 02:58:32 NOTICE: (15) : delete-file TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/file1.txt 2025/12/30 02:58:32 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:33 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" to be canonical "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2" 2025/12/30 02:58:34 INFO : file1.txt: Deleted 2025/12/30 02:58:34 NOTICE: (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2025/12/30 02:58:34 NOTICE: (17) : delete-file TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/file2.txt 2025/12/30 02:58:34 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" 2025/12/30 02:58:35 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" to be canonical "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1" 2025/12/30 02:58:36 INFO : file2.txt: Deleted 2025/12/30 02:58:36 NOTICE: (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2025/12/30 02:58:36 NOTICE: (19) : copy-as /tmp/025756pi/datadir/test_resync-casosip0/fileA.txt TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ file3.txt 2025/12/30 02:58:36 DEBUG : copyFile "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" to "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" as "file3.txt" 2025/12/30 02:58:36 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" 2025/12/30 02:58:36 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:37 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2" as "file3.txt" 2025/12/30 02:58:37 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/025756pi/datadir/test_resync-casosip0) 2025/12/30 02:58:37 DEBUG : file3.txt: size = 0 (B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2) 2025/12/30 02:58:37 DEBUG : fileA.txt: Sizes differ 2025/12/30 02:58:40 DEBUG : file3.txt: size = 19 OK 2025/12/30 02:58:40 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/30 02:58:40 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2025/12/30 02:58:40 NOTICE: (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2025/12/30 02:58:40 NOTICE: (21) : delete-file TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/file4.txt 2025/12/30 02:58:40 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" for canonical name "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1" 2025/12/30 02:58:40 INFO : file4.txt: Deleted 2025/12/30 02:58:40 NOTICE: (22) : copy-as /tmp/025756pi/datadir/test_resync-casosip0/fileA.txt TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ file4.txt 2025/12/30 02:58:40 DEBUG : copyFile "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" to "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" as "file4.txt" 2025/12/30 02:58:40 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" 2025/12/30 02:58:40 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:41 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2" as "file4.txt" 2025/12/30 02:58:42 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/025756pi/datadir/test_resync-casosip0) 2025/12/30 02:58:42 DEBUG : file4.txt: size = 0 (B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2) 2025/12/30 02:58:42 DEBUG : fileA.txt: Sizes differ 2025/12/30 02:58:43 DEBUG : file4.txt: size = 19 OK 2025/12/30 02:58:43 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/30 02:58:43 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2025/12/30 02:58:43 NOTICE: (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2025/12/30 02:58:43 NOTICE: (24) : copy-as /tmp/025756pi/datadir/test_resync-casosip0/fileB.txt TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ file5.txt 2025/12/30 02:58:43 DEBUG : copyFile "/tmp/025756pi/datadir/test_resync-casosip0/fileB.txt" to "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" as "file5.txt" 2025/12/30 02:58:43 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/fileB.txt" 2025/12/30 02:58:43 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:44 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2" as "file5.txt" 2025/12/30 02:58:45 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/025756pi/datadir/test_resync-casosip0) 2025/12/30 02:58:45 DEBUG : file5.txt: size = 0 (B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2) 2025/12/30 02:58:45 DEBUG : fileB.txt: Sizes differ 2025/12/30 02:58:46 DEBUG : file5.txt: size = 19 OK 2025/12/30 02:58:46 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/30 02:58:46 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2025/12/30 02:58:46 NOTICE: (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2025/12/30 02:58:46 NOTICE: (26) : copy-as /tmp/025756pi/datadir/test_resync-casosip0/fileB.txt TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/ file6.txt 2025/12/30 02:58:46 DEBUG : copyFile "/tmp/025756pi/datadir/test_resync-casosip0/fileB.txt" to "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" as "file6.txt" 2025/12/30 02:58:46 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/fileB.txt" 2025/12/30 02:58:46 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" 2025/12/30 02:58:47 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1" as "file6.txt" 2025/12/30 02:58:48 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/025756pi/datadir/test_resync-casosip0) 2025/12/30 02:58:48 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1) 2025/12/30 02:58:48 DEBUG : fileB.txt: Sizes differ 2025/12/30 02:58:50 DEBUG : file6.txt: size = 19 OK 2025/12/30 02:58:50 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/30 02:58:50 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2025/12/30 02:58:50 NOTICE: (27) : copy-as /tmp/025756pi/datadir/test_resync-casosip0/fileA.txt TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ file6.txt 2025/12/30 02:58:50 DEBUG : copyFile "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" to "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" as "file6.txt" 2025/12/30 02:58:50 DEBUG : Creating backend with remote "/tmp/025756pi/datadir/test_resync-casosip0/fileA.txt" 2025/12/30 02:58:50 DEBUG : Creating backend with remote "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:58:51 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2" as "file6.txt" 2025/12/30 02:58:51 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/025756pi/datadir/test_resync-casosip0) 2025/12/30 02:58:51 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2) 2025/12/30 02:58:51 DEBUG : fileA.txt: Sizes differ 2025/12/30 02:58:53 DEBUG : file6.txt: size = 19 OK 2025/12/30 02:58:53 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/12/30 02:58:53 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2025/12/30 02:58:53 NOTICE: (28) : test - file7.txt - exists - exists (same) - none - same 2025/12/30 02:58:53 NOTICE: (29) : test run bisync with resync 2025/12/30 02:58:53 NOTICE: (30) : bisync resync 2025/12/30 02:59:02 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:02 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:02 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/30 02:59:02 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/30 02:59:02 INFO : Synching Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" with Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:59:02 INFO : Copying Path2 files to Path1 2025/12/30 02:59:02 INFO : - Path2 Resync is copying files to - Path1 2025/12/30 02:59:02 INFO : file2.txt: Copied (server-side copy) 2025/12/30 02:59:03 INFO : file4.txt: Copied (server-side copy) 2025/12/30 02:59:03 INFO : - Path1 Resync is copying files to - Path2 2025/12/30 02:59:04 INFO : file1.txt: Copied (server-side copy) 2025/12/30 02:59:04 INFO : file3.txt: Copied (server-side copy) 2025/12/30 02:59:04 INFO : file5.txt: Copied (server-side copy) 2025/12/30 02:59:04 INFO : file6.txt: Updated modification time in destination 2025/12/30 02:59:04 INFO : Resync updating listings 2025/12/30 02:59:04 INFO : Validating listings for Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" vs Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:59:04 INFO : Bisync successful 2025/12/30 02:59:04 NOTICE: (31) : copy-listings mixed-diffs 2025/12/30 02:59:04 NOTICE: (32) : test run normal bisync 2025/12/30 02:59:04 NOTICE: (33) : bisync 2025/12/30 02:59:12 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:12 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:12 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/30 02:59:12 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/30 02:59:12 INFO : Synching Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" with Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:59:12 INFO : Building Path1 and Path2 listings 2025/12/30 02:59:12 INFO : Path1 checking for diffs 2025/12/30 02:59:12 INFO : Path2 checking for diffs 2025/12/30 02:59:12 INFO : No changes found 2025/12/30 02:59:12 INFO : Updating listings 2025/12/30 02:59:12 INFO : Validating listings for Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" vs Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:59:12 INFO : Bisync successful 2025/12/30 02:59:12 NOTICE: (34) : test 4. confirm critical error on normal sync of empty path. 2025/12/30 02:59:12 NOTICE: (35) : purge-children TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/ 2025/12/30 02:59:13 DEBUG : RCLONE_TEST: Remove file 2025/12/30 02:59:13 DEBUG : file1.txt: Remove file 2025/12/30 02:59:13 DEBUG : file2.txt: Remove file 2025/12/30 02:59:13 DEBUG : file3.txt: Remove file 2025/12/30 02:59:13 DEBUG : file4.txt: Remove file 2025/12/30 02:59:13 DEBUG : file5.txt: Remove file 2025/12/30 02:59:14 DEBUG : file6.txt: Remove file 2025/12/30 02:59:14 DEBUG : file7.txt: Remove file 2025/12/30 02:59:14 NOTICE: (36) : bisync 2025/12/30 02:59:22 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:22 DEBUG : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/12/30 02:59:22 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/12/30 02:59:22 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/30 02:59:22 INFO : Synching Path1 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path1/" with Path2 "TestB2:rclone-test-tatezox3wemo/025756pi/resync/path2/" 2025/12/30 02:59:22 INFO : Building Path1 and Path2 listings 2025/12/30 02:59:22 INFO : Path1 checking for diffs 2025/12/30 02:59:22 INFO : Path2 checking for diffs 2025/12/30 02:59:22 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/025756pi/workdir/TestB2_rclone-test-tatezox3wemo_025756pi_resync_path1..TestB2_rclone-test-tatezox3wemo_025756pi_resync_path2.path2.lst-new 2025/12/30 02:59:22 ERROR : Bisync critical error: empty current Path2 listing: /tmp/025756pi/workdir/TestB2_rclone-test-tatezox3wemo_025756pi_resync_path1..TestB2_rclone-test-tatezox3wemo_025756pi_resync_path2.path2.lst-new 2025/12/30 02:59:22 ERROR : Bisync aborted. Must run --resync to recover. 2025/12/30 02:59:22 NOTICE: Bisync error: bisync aborted 2025/12/30 02:59:22 INFO : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path1: cleaning bucket "rclone-test-tatezox3wemo" of all files 2025/12/30 02:59:23 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10845279f1c4e038_d20251230_m025818_c001_v0001093_t0017_u01767063498581") 2025/12/30 02:59:23 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abd6_d20251230_m025807_c001_v7007000_t0000_u01767063487507") 2025/12/30 02:59:23 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10120bf6883d95f2_d20251230_m025803_c001_v0001167_t0045_u01767063483572") 2025/12/30 02:59:23 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff8d16_d20251230_m025819_c001_v0001185_t0036_u01767063499002") 2025/12/30 02:59:23 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abd7_d20251230_m025807_c001_v7007000_t0000_u01767063487691") 2025/12/30 02:59:23 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10229a04c2702999_d20251230_m025803_c001_v0001090_t0035_u01767063483044") 2025/12/30 02:59:23 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f11885c0840e94cca_d20251230_m025902_c001_v0001181_t0021_u01767063542404") 2025/12/30 02:59:23 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4001f83a6089fdb5_d20251230_m025836_c001_v7007000_t0000_u01767063516399") 2025/12/30 02:59:23 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f11444ab3f2f5140d_d20251230_m025818_c001_v0001182_t0052_u01767063498923") 2025/12/30 02:59:23 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abd8_d20251230_m025807_c001_v7007000_t0000_u01767063487874") 2025/12/30 02:59:23 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff8bb1_d20251230_m025803_c001_v0001185_t0000_u01767063483158") 2025/12/30 02:59:23 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e279c_d20251230_m025818_c001_v0001185_t0038_u01767063498927") 2025/12/30 02:59:23 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abd9_d20251230_m025808_c001_v7007000_t0000_u01767063488096") 2025/12/30 02:59:23 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1180a738f5be5a3f_d20251230_m025803_c001_v0001165_t0049_u01767063483380") 2025/12/30 02:59:23 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f107d352af3565626_d20251230_m025902_c001_v0001173_t0014_u01767063542841") 2025/12/30 02:59:23 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4001f83a6089fdce_d20251230_m025840_c001_v7007000_t0000_u01767063520602") 2025/12/30 02:59:23 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f116ab34f2a13845e_d20251230_m025818_c001_v0001173_t0017_u01767063498960") 2025/12/30 02:59:23 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abda_d20251230_m025808_c001_v7007000_t0000_u01767063488303") 2025/12/30 02:59:23 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff8bb4_d20251230_m025803_c001_v0001185_t0029_u01767063483367") 2025/12/30 02:59:23 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105be9eb2f3bd37b_d20251230_m025819_c001_v0001137_t0052_u01767063499149") 2025/12/30 02:59:23 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abdb_d20251230_m025808_c001_v7007000_t0000_u01767063488488") 2025/12/30 02:59:24 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff8bba_d20251230_m025803_c001_v0001185_t0026_u01767063483556") 2025/12/30 02:59:24 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f107d352af356555f_d20251230_m025849_c001_v0001173_t0050_u01767063529455") 2025/12/30 02:59:24 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e27a6_d20251230_m025819_c001_v0001185_t0050_u01767063499154") 2025/12/30 02:59:24 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abdc_d20251230_m025808_c001_v7007000_t0000_u01767063488675") 2025/12/30 02:59:24 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10229a04c270299b_d20251230_m025803_c001_v0001090_t0019_u01767063483618") 2025/12/30 02:59:24 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f11463689506c527a_d20251230_m025819_c001_v0001037_t0052_u01767063499214") 2025/12/30 02:59:24 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abdd_d20251230_m025808_c001_v7007000_t0000_u01767063488862") 2025/12/30 02:59:24 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1180a738f5be5a41_d20251230_m025803_c001_v0001165_t0012_u01767063483645") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4160aba36fa08c88_d20251230_m025918_c001_v7007000_t0000_u01767063558073") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f118607fc50ce33ca_d20251230_m025916_c001_v0001185_t0027_u01767063556891") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff91ed_d20251230_m025915_c001_v0001185_t0015_u01767063555011") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4160aba36fa08c51_d20251230_m025908_c001_v7007000_t0000_u01767063548368") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f116ec0de972f4fcb_d20251230_m025907_c001_v0001181_t0023_u01767063547154") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10120bf6883d9b85_d20251230_m025904_c001_v0001167_t0016_u01767063544986") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4160aba36fa08c18_d20251230_m025857_c001_v7007000_t0000_u01767063537745") 2025/12/30 02:59:24 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1037327a8c537c93_d20251230_m025856_c001_v0001167_t0041_u01767063536111") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10229a04c27029b8_d20251230_m025853_c001_v0001090_t0008_u01767063533710") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f410a873dfb48afb7_d20251230_m025825_c001_v7007000_t0000_u01767063505864") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1044ba3f641c0e55_d20251230_m025824_c001_v0001109_t0047_u01767063504186") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1180a738f5be5a54_d20251230_m025822_c001_v0001165_t0044_u01767063502384") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f42064bfc5021abe0_d20251230_m025812_c001_v7007000_t0000_u01767063492774") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1004d9498a116644_d20251230_m025811_c001_v0001091_t0005_u01767063491236") 2025/12/30 02:59:25 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1171cdba25ff8c3f_d20251230_m025809_c001_v0001185_t0000_u01767063489406") 2025/12/30 02:59:25 INFO : B2 bucket rclone-test-tatezox3wemo path 025756pi/resync/path2: cleaning bucket "rclone-test-tatezox3wemo" of all files 2025/12/30 02:59:26 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f411_d20251230_m025913_c001_v7007000_t0000_u01767063553113") 2025/12/30 02:59:26 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1046231cbafd57af_d20251230_m025831_c001_v0001106_t0024_u01767063511092") 2025/12/30 02:59:26 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d14442588_d20251230_m025820_c001_v7007000_t0000_u01767063500379") 2025/12/30 02:59:26 DEBUG : RCLONE_TEST: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1003d468060ede13_d20251230_m025806_c001_v0001113_t0003_u01767063486226") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f412_d20251230_m025913_c001_v7007000_t0000_u01767063553299") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f119ec2f5cff1a2bb_d20251230_m025903_c001_v0001134_t0046_u01767063543819") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f406a9478805bb0ac_d20251230_m025834_c001_v7007000_t0000_u01767063514543") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f113933cd6668f7c2_d20251230_m025831_c001_v0001032_t0049_u01767063511469") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d14442589_d20251230_m025820_c001_v7007000_t0000_u01767063500581") 2025/12/30 02:59:26 DEBUG : file1.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f100d2bac98efe11e_d20251230_m025805_c001_v0001167_t0043_u01767063485636") 2025/12/30 02:59:26 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f413_d20251230_m025913_c001_v7007000_t0000_u01767063553482") 2025/12/30 02:59:26 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f107ebb1e872b5421_d20251230_m025831_c001_v0001092_t0050_u01767063511484") 2025/12/30 02:59:26 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258a_d20251230_m025820_c001_v7007000_t0000_u01767063500766") 2025/12/30 02:59:26 DEBUG : file2.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1083d5b407111b2a_d20251230_m025805_c001_v0001137_t0054_u01767063485844") 2025/12/30 02:59:26 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f414_d20251230_m025913_c001_v7007000_t0000_u01767063553677") 2025/12/30 02:59:26 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1139a7875a350303_d20251230_m025904_c001_v0001181_t0020_u01767063544199") 2025/12/30 02:59:26 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1104c9ae68981ddb_d20251230_m025839_c001_v0001173_t0055_u01767063519237") 2025/12/30 02:59:26 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10120bf6883d9896_d20251230_m025831_c001_v0001167_t0018_u01767063511483") 2025/12/30 02:59:26 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258b_d20251230_m025820_c001_v7007000_t0000_u01767063500952") 2025/12/30 02:59:27 DEBUG : file3.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e26ce_d20251230_m025806_c001_v0001185_t0000_u01767063486039") 2025/12/30 02:59:27 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f415_d20251230_m025913_c001_v7007000_t0000_u01767063553874") 2025/12/30 02:59:27 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f100de20320e12798_d20251230_m025843_c001_v0001173_t0029_u01767063523398") 2025/12/30 02:59:27 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1118f55407db1595_d20251230_m025831_c001_v0001173_t0015_u01767063511672") 2025/12/30 02:59:27 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258c_d20251230_m025821_c001_v7007000_t0000_u01767063501165") 2025/12/30 02:59:27 DEBUG : file4.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f100d2bac98efe126_d20251230_m025806_c001_v0001167_t0047_u01767063486139") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f416_d20251230_m025914_c001_v7007000_t0000_u01767063554057") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f107fd83aa09dc403_d20251230_m025904_c001_v0001185_t0052_u01767063544213") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f115c153dd46f6cfa_d20251230_m025846_c001_v0001179_t0017_u01767063526596") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f106f509db9775f7f_d20251230_m025831_c001_v0001105_t0039_u01767063511833") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258d_d20251230_m025821_c001_v7007000_t0000_u01767063501352") 2025/12/30 02:59:27 DEBUG : file5.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1083d5b407111b2c_d20251230_m025806_c001_v0001137_t0054_u01767063486164") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f417_d20251230_m025914_c001_v7007000_t0000_u01767063554280") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10114756b76f5921_d20251230_m025904_c001_v0001032_t0046_u01767063544269") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10120bf6883d9a6c_d20251230_m025852_c001_v0001167_t0053_u01767063532770") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e28bb_d20251230_m025831_c001_v0001185_t0057_u01767063511844") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258e_d20251230_m025821_c001_v7007000_t0000_u01767063501568") 2025/12/30 02:59:27 DEBUG : file6.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e26d5_d20251230_m025806_c001_v0001185_t0010_u01767063486328") 2025/12/30 02:59:27 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f418_d20251230_m025914_c001_v7007000_t0000_u01767063554464") 2025/12/30 02:59:27 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f10060773a2a53d1e_d20251230_m025832_c001_v0001161_t0051_u01767063512052") 2025/12/30 02:59:28 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444258f_d20251230_m025821_c001_v7007000_t0000_u01767063501754") 2025/12/30 02:59:28 DEBUG : file7.txt: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e26d9_d20251230_m025806_c001_v0001185_t0003_u01767063486517") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f409e4701dab4eff3_d20251230_m025922_c001_v7007000_t0000_u01767063562541") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f113d509e96c30888_d20251230_m025921_c001_v0001167_t0046_u01767063561001") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1083d5b407111b7f_d20251230_m025918_c001_v0001137_t0039_u01767063558688") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f410_d20251230_m025912_c001_v7007000_t0000_u01767063552538") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f107fd83aa09dc4c6_d20251230_m025911_c001_v0001185_t0047_u01767063551311") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f100d2bac98efe67f_d20251230_m025908_c001_v0001167_t0026_u01767063548940") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f41958158be78f407_d20251230_m025902_c001_v7007000_t0000_u01767063542004") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1047d9941a6ba79d_d20251230_m025900_c001_v0001092_t0012_u01767063540439") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f105f3ce2cd9e2ad0_d20251230_m025858_c001_v0001185_t0017_u01767063538297") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f406a9478805bb0aa_d20251230_m025830_c001_v7007000_t0000_u01767063510497") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1118f55407db1559_d20251230_m025828_c001_v0001173_t0055_u01767063508885") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1003d468060ede21_d20251230_m025826_c001_v0001113_t0028_u01767063506466") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f4131050d1444257e_d20251230_m025817_c001_v7007000_t0000_u01767063497750") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f117cebaf0a500e9e_d20251230_m025815_c001_v0001167_t0018_u01767063495731") 2025/12/30 02:59:28 DEBUG : modtime_write_test: Deleting (id "4_z1fe2dde3300d6bdb9fb80e1b_f1083d5b407111b3a_d20251230_m025813_c001_v0001137_t0031_u01767063493340") bisync_test.go:608: TEST resync PASSED 2025/12/30 02:59:29 DEBUG : B2 bucket rclone-test-tatezox3wemo: Purge remote 2025/12/30 02:59:29 INFO : B2 bucket rclone-test-tatezox3wemo: cleaning bucket "rclone-test-tatezox3wemo" of all files --- PASS: TestBisyncRemoteRemote (94.94s) --- PASS: TestBisyncRemoteRemote/resync (93.15s) PASS 2025/12/30 02:59:30 DEBUG : B2 bucket rclone-test-xuyiwij2yupa: Purge remote 2025/12/30 02:59:30 INFO : B2 bucket rclone-test-xuyiwij2yupa: cleaning bucket "rclone-test-xuyiwij2yupa" of all files 2025/12/30 02:59:31 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^resync$'" - Finished OK in 1m36.332628789s (try 3/5)