"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^resolve$'" - Starting (try 5/5) 2025/11/14 05:48:34 DEBUG : Creating backend with remote "TestB2:rclone-test-womukod2daci" 2025/11/14 05:48:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/14 05:48:35 DEBUG : Creating backend with remote "/tmp/rclone699062751" === RUN TestBisyncRemoteRemote 2025/11/14 05:48:35 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe" 2025/11/14 05:48:35 NOTICE: remote: TestB2:rclone-test-quzasef7gufe === RUN TestBisyncRemoteRemote/resolve 2025/11/14 05:48:35 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li" 2025/11/14 05:48:37 INFO : path1: Making directory 2025/11/14 05:48:38 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1" 2025/11/14 05:48:39 INFO : path2: Making directory 2025/11/14 05:48:39 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2" 2025/11/14 05:48:40 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial" 2025/11/14 05:48:40 DEBUG : Creating backend with remote "/tmp/054835li/initdir/test_resolve-gudutec4" 2025/11/14 05:48:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : Local file system at /tmp/054835li/initdir/test_resolve-gudutec4: Waiting for checks to finish 2025/11/14 05:48:40 DEBUG : Local file system at /tmp/054835li/initdir/test_resolve-gudutec4: Waiting for transfers to finish 2025/11/14 05:48:40 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2025/11/14 05:48:40 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/11/14 05:48:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/14 05:48:40 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/14 05:48:40 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/11/14 05:48:40 INFO : file1.txt: Copied (new) 2025/11/14 05:48:40 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/11/14 05:48:40 INFO : file2.txt: Copied (new) 2025/11/14 05:48:40 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/11/14 05:48:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/11/14 05:48:40 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/11/14 05:48:40 INFO : RCLONE_TEST: Copied (new) 2025/11/14 05:48:40 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles" 2025/11/14 05:48:40 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:48:40 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:40 DEBUG : Local file system at /tmp/054835li/datadir/test_resolve-cokezis0: Waiting for checks to finish 2025/11/14 05:48:40 DEBUG : Local file system at /tmp/054835li/datadir/test_resolve-cokezis0: Waiting for transfers to finish 2025/11/14 05:48:40 DEBUG : file2.txt.67fcf9d0.partial: size = 37 OK 2025/11/14 05:48:40 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2025/11/14 05:48:40 DEBUG : file1R.txt.f5ef699a.partial: size = 33 OK 2025/11/14 05:48:40 DEBUG : file2.txt.67fcf9d0.partial: renamed to: file2.txt 2025/11/14 05:48:40 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK 2025/11/14 05:48:40 INFO : file2.txt: Copied (new) 2025/11/14 05:48:40 DEBUG : file1R.txt.f5ef699a.partial: renamed to: file1R.txt 2025/11/14 05:48:40 INFO : file1R.txt: Copied (new) 2025/11/14 05:48:40 DEBUG : file1L.txt.6455455d.partial: size = 33 OK 2025/11/14 05:48:40 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2025/11/14 05:48:40 DEBUG : file1L.txt.6455455d.partial: renamed to: file1L.txt 2025/11/14 05:48:40 INFO : file1L.txt: Copied (new) 2025/11/14 05:48:40 INFO : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: cleaning bucket "rclone-test-quzasef7gufe" of all files 2025/11/14 05:48:41 INFO : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: cleaning bucket "rclone-test-quzasef7gufe" of all files 2025/11/14 05:48:42 NOTICE: checking initFs Local file system at /tmp/054835li/initdir/test_resolve-gudutec4 2025/11/14 05:48:42 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/14 05:48:42 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:42 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:42 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: Waiting for checks to finish 2025/11/14 05:48:42 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: Waiting for transfers to finish 2025/11/14 05:48:43 DEBUG : file2.txt: size = 0 OK 2025/11/14 05:48:43 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/11/14 05:48:43 INFO : file2.txt: Copied (new) 2025/11/14 05:48:43 DEBUG : RCLONE_TEST: size = 109 OK 2025/11/14 05:48:43 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/11/14 05:48:43 INFO : RCLONE_TEST: Copied (new) 2025/11/14 05:48:44 DEBUG : file1.txt: size = 0 OK 2025/11/14 05:48:44 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/11/14 05:48:44 INFO : file1.txt: Copied (new) 2025/11/14 05:48:44 NOTICE: checking Path1 B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1 2025/11/14 05:48:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/14 05:48:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:44 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/11/14 05:48:44 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: Waiting for checks to finish 2025/11/14 05:48:44 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: Waiting for transfers to finish 2025/11/14 05:48:45 DEBUG : file1.txt: size = 0 OK 2025/11/14 05:48:45 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/11/14 05:48:45 INFO : file1.txt: Copied (new) 2025/11/14 05:48:45 DEBUG : file2.txt: size = 0 OK 2025/11/14 05:48:45 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/11/14 05:48:45 INFO : file2.txt: Copied (new) 2025/11/14 05:48:45 DEBUG : RCLONE_TEST: size = 109 OK 2025/11/14 05:48:45 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/11/14 05:48:45 INFO : RCLONE_TEST: Copied (new) 2025/11/14 05:48:45 NOTICE: checking path2 B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2 2025/11/14 05:48:45 NOTICE: (01) : test resolve 2025/11/14 05:48:45 NOTICE: (02) : test initial bisync 2025/11/14 05:48:45 NOTICE: (03) : bisync resync 2025/11/14 05:48:53 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:48:53 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:48:53 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/11/14 05:48:53 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/11/14 05:48:53 INFO : Synching Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" with Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:48:53 INFO : Copying Path2 files to Path1 2025/11/14 05:48:53 INFO : - Path2 Resync is copying files to - Path1 2025/11/14 05:48:53 INFO : There was nothing to transfer 2025/11/14 05:48:53 INFO : - Path1 Resync is copying files to - Path2 2025/11/14 05:48:53 INFO : There was nothing to transfer 2025/11/14 05:48:53 INFO : Resync updating listings 2025/11/14 05:48:53 INFO : Validating listings for Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" vs Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:48:53 INFO : Bisync successful 2025/11/14 05:48:53 NOTICE: (04) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/11/14 05:48:53 NOTICE: (05) : touch-glob 2001-01-02 /tmp/054835li/datadir/test_resolve-cokezis0/ file1R.txt 2025/11/14 05:48:53 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/" 2025/11/14 05:48:53 DEBUG : fs cache: renaming cache item "/tmp/054835li/datadir/test_resolve-cokezis0/" to be canonical "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:48:53 DEBUG : file1R.txt: Set modification time 2001-01-02 2025/11/14 05:48:53 NOTICE: (06) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/ file1.txt 2025/11/14 05:48:53 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" as "file1.txt" 2025/11/14 05:48:53 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" 2025/11/14 05:48:53 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:48:54 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2" as "file1.txt" 2025/11/14 05:48:55 DEBUG : file1R.txt: size = 33 (Local file system at /tmp/054835li/datadir/test_resolve-cokezis0) 2025/11/14 05:48:55 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2) 2025/11/14 05:48:55 DEBUG : file1R.txt: Sizes differ 2025/11/14 05:48:56 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:48:56 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK 2025/11/14 05:48:56 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/11/14 05:48:56 NOTICE: (07) : touch-glob 2001-03-04 /tmp/054835li/datadir/test_resolve-cokezis0/ file1L.txt 2025/11/14 05:48:56 DEBUG : fs cache: switching user supplied name "/tmp/054835li/datadir/test_resolve-cokezis0/" for canonical name "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:48:56 DEBUG : file1L.txt: Set modification time 2001-03-04 2025/11/14 05:48:56 NOTICE: (08) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/ file1.txt 2025/11/14 05:48:56 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" as "file1.txt" 2025/11/14 05:48:56 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" 2025/11/14 05:48:56 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" 2025/11/14 05:48:57 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1" as "file1.txt" 2025/11/14 05:48:58 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/054835li/datadir/test_resolve-cokezis0) 2025/11/14 05:48:58 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1) 2025/11/14 05:48:58 DEBUG : file1L.txt: Sizes differ 2025/11/14 05:48:59 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:48:59 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/11/14 05:48:59 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/11/14 05:48:59 NOTICE: (09) : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato 2025/11/14 05:48:59 NOTICE: (10) : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato 2025/11/14 05:49:08 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:49:08 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:49:08 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/11/14 05:49:08 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/11/14 05:49:08 INFO : Synching Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" with Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:08 INFO : Building Path1 and Path2 listings 2025/11/14 05:49:08 INFO : Path1 checking for diffs 2025/11/14 05:49:08 INFO : - Path1 File changed: size (larger), time (newer) - file1.txt 2025/11/14 05:49:08 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2025/11/14 05:49:08 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/11/14 05:49:08 INFO : Path2 checking for diffs 2025/11/14 05:49:08 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/11/14 05:49:08 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/11/14 05:49:08 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/11/14 05:49:08 INFO : Applying changes 2025/11/14 05:49:08 INFO : Checking potential conflicts... 2025/11/14 05:49:08 ERROR : file1.txt: sha1 differ 2025/11/14 05:49:08 NOTICE: B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: 1 differences found 2025/11/14 05:49:08 NOTICE: B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: 1 errors while checking 2025/11/14 05:49:08 INFO : Finished checking the potential conflicts. 1 differences found 2025/11/14 05:49:08 NOTICE: - WARNING New or changed in both paths - file1.txt 2025/11/14 05:49:08 INFO : file1.txt: Path1 is newer. Path1: 2001-03-04 00:00:00 +0000 UTC, Path2: 2001-01-02 00:00:00 +0000 UTC, Difference: 1464h0m0s 2025/11/14 05:49:08 INFO : file1.txt: The winner is: Path1 2025/11/14 05:49:08 NOTICE: - Path2 Deleting Path2 copy - TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/file1.txt 2025/11/14 05:49:08 INFO : file1.txt: Deleted 2025/11/14 05:49:08 INFO : - Path1 Queue copy to Path2 - file1.txt 2025/11/14 05:49:08 INFO : - Path1 Do queued copies to - Path2 2025/11/14 05:49:09 INFO : file1.txt: Copied (server-side copy) 2025/11/14 05:49:09 INFO : Updating listings 2025/11/14 05:49:09 INFO : Validating listings for Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" vs Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:09 INFO : Bisync successful 2025/11/14 05:49:09 NOTICE: (11) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/11/14 05:49:09 NOTICE: (12) : touch-glob 2001-07-23 /tmp/054835li/datadir/test_resolve-cokezis0/ file1R.txt 2025/11/14 05:49:09 DEBUG : fs cache: switching user supplied name "/tmp/054835li/datadir/test_resolve-cokezis0/" for canonical name "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:49:09 DEBUG : file1R.txt: Set modification time 2001-07-23 2025/11/14 05:49:09 NOTICE: (13) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/ file1.txt 2025/11/14 05:49:09 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" as "file1.txt" 2025/11/14 05:49:09 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" 2025/11/14 05:49:09 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:10 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2" as "file1.txt" 2025/11/14 05:49:10 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:10 DEBUG : file1R.txt: Modification times differ by -3384h0m0s: 2001-07-23 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC 2025/11/14 05:49:10 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 (Local file system at /tmp/054835li/datadir/test_resolve-cokezis0) 2025/11/14 05:49:10 DEBUG : file1.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd (B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2) 2025/11/14 05:49:10 DEBUG : file1R.txt: sha1 differ 2025/11/14 05:49:12 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:12 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK 2025/11/14 05:49:12 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/11/14 05:49:12 NOTICE: (14) : touch-glob 2001-08-26 /tmp/054835li/datadir/test_resolve-cokezis0/ file1L.txt 2025/11/14 05:49:12 DEBUG : fs cache: switching user supplied name "/tmp/054835li/datadir/test_resolve-cokezis0/" for canonical name "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:49:12 DEBUG : file1L.txt: Set modification time 2001-08-26 2025/11/14 05:49:12 NOTICE: (15) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/ file1.txt 2025/11/14 05:49:12 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" as "file1.txt" 2025/11/14 05:49:12 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" 2025/11/14 05:49:12 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" 2025/11/14 05:49:13 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1" as "file1.txt" 2025/11/14 05:49:13 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:13 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC 2025/11/14 05:49:13 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/11/14 05:49:15 INFO : file1L.txt: Updated modification time in destination 2025/11/14 05:49:15 DEBUG : file1L.txt: Unchanged skipping 2025/11/14 05:49:15 NOTICE: (16) : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur 2025/11/14 05:49:15 NOTICE: (17) : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur 2025/11/14 05:49:24 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:49:24 DEBUG : B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/11/14 05:49:24 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/11/14 05:49:24 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/11/14 05:49:24 INFO : Synching Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" with Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:24 INFO : Building Path1 and Path2 listings 2025/11/14 05:49:24 INFO : Path1 checking for diffs 2025/11/14 05:49:24 INFO : - Path1 File changed: time (newer) - file1.txt 2025/11/14 05:49:24 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2025/11/14 05:49:24 INFO : (Modified:  1 newer,  0 older) 2025/11/14 05:49:24 INFO : Path2 checking for diffs 2025/11/14 05:49:24 INFO : - Path2 File changed: time (newer) - file1.txt 2025/11/14 05:49:24 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/11/14 05:49:24 INFO : (Modified:  1 newer,  0 older) 2025/11/14 05:49:24 INFO : Applying changes 2025/11/14 05:49:24 INFO : Checking potential conflicts... 2025/11/14 05:49:24 ERROR : file1.txt: sha1 differ 2025/11/14 05:49:24 NOTICE: B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: 1 differences found 2025/11/14 05:49:24 NOTICE: B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2: 1 errors while checking 2025/11/14 05:49:24 INFO : Finished checking the potential conflicts. 1 differences found 2025/11/14 05:49:24 NOTICE: - WARNING New or changed in both paths - file1.txt 2025/11/14 05:49:24 INFO : file1.txt: The winner is: Path2 2025/11/14 05:49:24 NOTICE: - Path1 Renaming Path1 copy - TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/file1.txt.dinosaur1 2025/11/14 05:49:26 INFO : file1.txt: Copied (server-side copy) to: file1.txt.dinosaur1 2025/11/14 05:49:26 INFO : file1.txt: Deleted 2025/11/14 05:49:26 NOTICE: - Path1 Queue copy to Path2 - TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/file1.txt.dinosaur1 2025/11/14 05:49:26 NOTICE: - Path2 Not renaming Path2 copy, as it was determined the winner - TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/file1.txt 2025/11/14 05:49:26 NOTICE: - Path2 Queue copy to Path1 - TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/file1.txt 2025/11/14 05:49:26 INFO : - Path2 Do queued copies to - Path1 2025/11/14 05:49:27 INFO : file1.txt: Copied (server-side copy) 2025/11/14 05:49:27 INFO : - Path1 Do queued copies to - Path2 2025/11/14 05:49:28 INFO : file1.txt.dinosaur1: Copied (server-side copy) 2025/11/14 05:49:28 INFO : Updating listings 2025/11/14 05:49:28 INFO : Validating listings for Path1 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" vs Path2 "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:28 INFO : Bisync successful 2025/11/14 05:49:28 NOTICE: (18) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/11/14 05:49:28 NOTICE: (19) : touch-glob 2002-07-23 /tmp/054835li/datadir/test_resolve-cokezis0/ file1R.txt 2025/11/14 05:49:28 DEBUG : fs cache: switching user supplied name "/tmp/054835li/datadir/test_resolve-cokezis0/" for canonical name "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:49:28 DEBUG : file1R.txt: Set modification time 2002-07-23 2025/11/14 05:49:28 NOTICE: (20) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/ file1.txt 2025/11/14 05:49:28 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" as "file1.txt" 2025/11/14 05:49:28 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1R.txt" 2025/11/14 05:49:28 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path2/" 2025/11/14 05:49:29 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path2" as "file1.txt" 2025/11/14 05:49:29 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:29 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC 2025/11/14 05:49:29 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK 2025/11/14 05:49:31 INFO : file1R.txt: Updated modification time in destination 2025/11/14 05:49:31 DEBUG : file1R.txt: Unchanged skipping 2025/11/14 05:49:31 NOTICE: (21) : touch-glob 2002-08-26 /tmp/054835li/datadir/test_resolve-cokezis0/ file1L.txt 2025/11/14 05:49:31 DEBUG : fs cache: switching user supplied name "/tmp/054835li/datadir/test_resolve-cokezis0/" for canonical name "/tmp/054835li/datadir/test_resolve-cokezis0" 2025/11/14 05:49:31 DEBUG : file1L.txt: Set modification time 2002-08-26 2025/11/14 05:49:31 NOTICE: (22) : copy-as /tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/ file1.txt 2025/11/14 05:49:31 DEBUG : copyFile "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" to "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" as "file1.txt" 2025/11/14 05:49:31 DEBUG : Creating backend with remote "/tmp/054835li/datadir/test_resolve-cokezis0/file1L.txt" 2025/11/14 05:49:31 DEBUG : Creating backend with remote "TestB2:rclone-test-quzasef7gufe/054835li/resolve/path1/" 2025/11/14 05:49:32 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1" as "file1.txt" 2025/11/14 05:49:32 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:32 DEBUG : file1L.txt: Modification times differ by -9576h0m0s: 2002-08-26 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC 2025/11/14 05:49:32 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd (Local file system at /tmp/054835li/datadir/test_resolve-cokezis0) 2025/11/14 05:49:32 DEBUG : file1.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 (B2 bucket rclone-test-quzasef7gufe path 054835li/resolve/path1) 2025/11/14 05:49:32 DEBUG : file1L.txt: sha1 differ 2025/11/14 05:49:34 DEBUG : file1.txt: size = 33 OK 2025/11/14 05:49:34 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/11/14 05:49:34 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/11/14 05:49:34 NOTICE: (23) : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple 2025/11/14 05:49:34 NOTICE: (24) : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple 2025/11/14 05:49:39 DEBUG : modtime_write_test: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/14 05:49:39 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/14 05:49:39 DEBUG : pacer: Rate limited, increasing sleep to 1s bisync_test.go:1025: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1025 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1046 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542 /usr/local/go/src/strings/iter.go:61 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 Error: Received unexpected error: no tomes available (503 service_unavailable): trying again in 1s Test: TestBisyncRemoteRemote/resolve 2025/11/14 05:49:39 DEBUG : B2 bucket rclone-test-quzasef7gufe: Purge remote 2025/11/14 05:49:39 INFO : B2 bucket rclone-test-quzasef7gufe: cleaning bucket "rclone-test-quzasef7gufe" of all files 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/RCLONE_TEST: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f116e098babf44914_d20251114_m054843_c001_v0001172_t0007_u01763099323489") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11030495d223e579_d20251114_m054934_c001_v0001104_t0008_u01763099374085") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f107b4e2a4bb144fa_d20251114_m054927_c001_v0001092_t0048_u01763099367507") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f415744888f477005_d20251114_m054926_c001_v7007000_t0000_u01763099366094") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f108fa1d17d369c67_d20251114_m054914_c001_v0001171_t0058_u01763099354731") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11469447557b9467_d20251114_m054859_c001_v0001177_t0059_u01763099339413") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11030495d223e4aa_d20251114_m054843_c001_v0001104_t0041_u01763099323295") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file1.txt.dinosaur1: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f119a20760844c110_d20251114_m054925_c001_v0001145_t0021_u01763099365717") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/file2.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11322a9f4df2a9b9_d20251114_m054843_c001_v0001044_t0010_u01763099323103") 2025/11/14 05:49:40 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f4011d72800664739_d20251114_m054939_c001_v7007000_t0000_u01763099379308") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1111c784248caa0e_d20251114_m054938_c001_v0001178_t0046_u01763099378093") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11322a9f4df2aaea_d20251114_m054935_c001_v0001044_t0004_u01763099375376") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f415744888f476fff_d20251114_m054919_c001_v7007000_t0000_u01763099359805") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f113de4198b91c68c_d20251114_m054918_c001_v0001116_t0002_u01763099358213") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11030495d223e527_d20251114_m054915_c001_v0001104_t0050_u01763099355727") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f415744888f476ff6_d20251114_m054903_c001_v7007000_t0000_u01763099343548") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1012ad785eb9bf05_d20251114_m054902_c001_v0001181_t0059_u01763099342297") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f116e098babf44961_d20251114_m054900_c001_v0001172_t0017_u01763099340006") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f40937c6354012257_d20251114_m054849_c001_v7007000_t0000_u01763099329282") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1198638621e83850_d20251114_m054847_c001_v0001032_t0018_u01763099327909") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path1/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11322a9f4df2a9ca_d20251114_m054845_c001_v0001044_t0053_u01763099325944") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/RCLONE_TEST: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f100f07c276466925_d20251114_m054845_c001_v0001032_t0035_u01763099325427") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11585bbeb5a00393_d20251114_m054930_c001_v0001036_t0052_u01763099370850") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f110971d9fa8cf8ed_d20251114_m054912_c001_v0001180_t0026_u01763099352305") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f113611bb4843fb9e_d20251114_m054909_c001_v0001093_t0059_u01763099349086") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f4067c17e4acd44de_d20251114_m054908_c001_v7007000_t0000_u01763099348692") 2025/11/14 05:49:41 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1035a1ecb42b7be3_d20251114_m054856_c001_v0001178_t0033_u01763099336506") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/file1.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f116ded3322ea9b80_d20251114_m054845_c001_v0001182_t0021_u01763099325062") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/file1.txt.dinosaur1: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f106b7fed0c2adb8d_d20251114_m054928_c001_v0001134_t0004_u01763099368019") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/file2.txt: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1196c46242d9ce13_d20251114_m054845_c001_v0001146_t0043_u01763099325240") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f41096641a40aa884_d20251114_m054924_c001_v7007000_t0000_u01763099364426") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f11371453cc4e830b_d20251114_m054922_c001_v0001038_t0011_u01763099362866") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f100f07c276466970_d20251114_m054920_c001_v0001032_t0042_u01763099360358") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f4067c17e4acd44da_d20251114_m054907_c001_v7007000_t0000_u01763099347960") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f118147c9d1885698_d20251114_m054906_c001_v0001040_t0054_u01763099346483") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1196c46242d9ce57_d20251114_m054904_c001_v0001146_t0043_u01763099344097") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f416e43f41c139bdd_d20251114_m054853_c001_v7007000_t0000_u01763099333278") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f1117aca1ee640d35_d20251114_m054851_c001_v0001102_t0006_u01763099331665") 2025/11/14 05:49:42 DEBUG : 054835li/resolve/path2/modtime_write_test: Deleting (id "4_z3f329d53e08d8b0b9fa80e1b_f116ded3322ea9b95_d20251114_m054849_c001_v0001182_t0035_u01763099329471") --- FAIL: TestBisyncRemoteRemote (68.68s) --- FAIL: TestBisyncRemoteRemote/resolve (64.01s) FAIL 2025/11/14 05:49:44 DEBUG : B2 bucket rclone-test-womukod2daci: Purge remote 2025/11/14 05:49:44 INFO : B2 bucket rclone-test-womukod2daci: cleaning bucket "rclone-test-womukod2daci" of all files 2025/11/14 05:49:44 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^resolve$'" - Finished ERROR in 1m10.084621634s (try 5/5): exit status 1: Failed [TestBisyncRemoteRemote/resolve]