"./bisync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestBisyncRemoteRemote$/^(check_access|resolve|resync)$'" - Starting (try 3/5) 2026/07/27 06:25:46 DEBUG : Creating backend with remote "TestDropbox:rclone-test-sosibob4hixu" 2026/07/27 06:25:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/27 06:25:47 DEBUG : Creating backend with remote "/tmp/rclone1049859650" === RUN TestBisyncRemoteRemote 2026/07/27 06:25:47 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga" 2026/07/27 06:25:47 NOTICE: remote: TestDropbox:rclone-test-yizuyal5kaga === RUN TestBisyncRemoteRemote/check_access 2026/07/27 06:25:47 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8" 2026/07/27 06:25:47 INFO : path1: Making directory 2026/07/27 06:25:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1" 2026/07/27 06:25:48 INFO : path2: Making directory 2026/07/27 06:25:49 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2" 2026/07/27 06:25:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/initial" 2026/07/27 06:25:49 DEBUG : Creating backend with remote "/tmp/062547copuvil8/initdir/test_check_access-hiriyos5" 2026/07/27 06:25:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : subdir: Making directory with metadata 2026/07/27 06:25:49 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/07/27 06:25:49 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/07/27 06:25:49 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/07/27 06:25:49 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:25:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/07/27 06:25:49 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/07/27 06:25:49 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_check_access-hiriyos5: Waiting for checks to finish 2026/07/27 06:25:49 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_check_access-hiriyos5: Waiting for transfers to finish 2026/07/27 06:25:49 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/07/27 06:25:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:25:49 INFO : file2.txt: Copied (new) 2026/07/27 06:25:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/07/27 06:25:49 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/07/27 06:25:49 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/07/27 06:25:49 INFO : file1.txt: Copied (new) 2026/07/27 06:25:49 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/07/27 06:25:49 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:25:49 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:25:49 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/07/27 06:25:49 INFO : file3.txt: Copied (new) 2026/07/27 06:25:49 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/07/27 06:25:49 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:25:49 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/07/27 06:25:49 INFO : file4.txt: Copied (new) 2026/07/27 06:25:49 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/07/27 06:25:49 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/07/27 06:25:49 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/07/27 06:25:49 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:25:49 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/07/27 06:25:49 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/07/27 06:25:49 INFO : subdir/RCLONE_TEST: Copied (new) 2026/07/27 06:25:49 INFO : subdir/file20.txt: Copied (new) 2026/07/27 06:25:49 INFO : subdir: Set directory modification time (using SetModTime) 2026/07/27 06:25:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/modfiles" 2026/07/27 06:25:49 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_check_access-damemeg3" 2026/07/27 06:25:49 DEBUG : hold.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:49 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_check_access-damemeg3: Waiting for checks to finish 2026/07/27 06:25:49 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_check_access-damemeg3: Waiting for transfers to finish 2026/07/27 06:25:49 DEBUG : hold.txt.83686dab.partial: size = 59 OK 2026/07/27 06:25:49 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2026/07/27 06:25:49 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt 2026/07/27 06:25:49 INFO : hold.txt: Copied (new) 2026/07/27 06:25:50 ERROR : error listing: directory not found 2026/07/27 06:25:50 ERROR : error listing: directory not found 2026/07/27 06:25:50 NOTICE: checking initFs Local file system at /tmp/062547copuvil8/initdir/test_check_access-hiriyos5 2026/07/27 06:25:51 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:51 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:51 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:51 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:51 INFO : subdir: Making directory 2026/07/27 06:25:51 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:25:51 DEBUG : file4.txt: Uploading chunk 1/0 2026/07/27 06:25:51 DEBUG : file3.txt: Uploading chunk 1/0 2026/07/27 06:25:51 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:25:52 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:52 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Waiting for checks to finish 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Waiting for transfers to finish 2026/07/27 06:25:52 DEBUG : file3.txt: Uploading chunk 2/0 2026/07/27 06:25:52 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:25:52 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:25:52 DEBUG : file4.txt: Uploading chunk 2/0 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "file3.txt" to batch 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "file2.txt" to batch 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "file4.txt" to batch 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "file1.txt" to batch 2026/07/27 06:25:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 4 starting with: file3.txt 2026/07/27 06:25:53 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 4 starting with: file3.txt 2026/07/27 06:25:53 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:25:53 DEBUG : file4.txt: size = 0 OK 2026/07/27 06:25:53 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:25:53 DEBUG : file4.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:53 INFO : file4.txt: Copied (new) 2026/07/27 06:25:53 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:53 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:53 INFO : file2.txt: Copied (new) 2026/07/27 06:25:53 INFO : file1.txt: Copied (new) 2026/07/27 06:25:53 DEBUG : file3.txt: size = 0 OK 2026/07/27 06:25:53 DEBUG : file3.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:53 INFO : file3.txt: Copied (new) 2026/07/27 06:25:53 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:25:53 DEBUG : subdir/RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:25:53 DEBUG : subdir/file20.txt: Uploading chunk 1/0 2026/07/27 06:25:54 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:25:54 DEBUG : subdir/file20.txt: Uploading chunk 2/0 2026/07/27 06:25:54 DEBUG : subdir/RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:25:54 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "RCLONE_TEST" to batch 2026/07/27 06:25:54 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "subdir/file20.txt" to batch 2026/07/27 06:25:54 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "subdir/RCLONE_TEST" to batch 2026/07/27 06:25:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:25:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 3 starting with: RCLONE_TEST 2026/07/27 06:25:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 3 starting with: RCLONE_TEST 2026/07/27 06:25:55 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/07/27 06:25:55 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:25:55 DEBUG : subdir/file20.txt: size = 0 OK 2026/07/27 06:25:55 DEBUG : subdir/RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:25:55 INFO : subdir/RCLONE_TEST: Copied (new) 2026/07/27 06:25:55 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:25:55 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:25:55 DEBUG : subdir/file20.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:55 INFO : subdir/file20.txt: Copied (new) 2026/07/27 06:25:55 NOTICE: checking Path1 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1' 2026/07/27 06:25:56 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:56 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:56 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:56 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:56 INFO : subdir: Making directory 2026/07/27 06:25:57 DEBUG : file3.txt: Uploading chunk 1/0 2026/07/27 06:25:57 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:25:57 DEBUG : file4.txt: Uploading chunk 1/0 2026/07/27 06:25:57 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:25:57 DEBUG : pacer: low level retry 1/10 (error ) 2026/07/27 06:25:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/07/27 06:25:57 DEBUG : pacer: Reducing sleep to 15ms 2026/07/27 06:25:57 DEBUG : file3.txt: Uploading chunk 2/0 2026/07/27 06:25:57 DEBUG : pacer: Reducing sleep to 11.25ms 2026/07/27 06:25:57 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:25:57 DEBUG : pacer: Reducing sleep to 10ms 2026/07/27 06:25:57 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:25:57 DEBUG : file4.txt: Uploading chunk 2/0 2026/07/27 06:25:57 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/07/27 06:25:57 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:25:57 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Waiting for checks to finish 2026/07/27 06:25:57 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Waiting for transfers to finish 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "file3.txt" to batch 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "file1.txt" to batch 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "file4.txt" to batch 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "file2.txt" to batch 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 4 starting with: file3.txt 2026/07/27 06:25:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 4 starting with: file3.txt 2026/07/27 06:25:58 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:25:58 DEBUG : file4.txt: size = 0 OK 2026/07/27 06:25:58 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:25:58 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:58 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:58 INFO : file1.txt: Copied (new) 2026/07/27 06:25:58 INFO : file2.txt: Copied (new) 2026/07/27 06:25:58 DEBUG : file4.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:58 INFO : file4.txt: Copied (new) 2026/07/27 06:25:58 DEBUG : file3.txt: size = 0 OK 2026/07/27 06:25:58 DEBUG : file3.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:25:58 INFO : file3.txt: Copied (new) 2026/07/27 06:25:59 DEBUG : subdir/file20.txt: Uploading chunk 1/0 2026/07/27 06:25:59 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:25:59 DEBUG : subdir/RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:25:59 DEBUG : subdir/file20.txt: Uploading chunk 2/0 2026/07/27 06:25:59 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:25:59 DEBUG : subdir/RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:26:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "subdir/file20.txt" to batch 2026/07/27 06:26:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "RCLONE_TEST" to batch 2026/07/27 06:26:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "subdir/RCLONE_TEST" to batch 2026/07/27 06:26:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 3 starting with: subdir/file20.txt 2026/07/27 06:26:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 3 starting with: subdir/file20.txt 2026/07/27 06:26:01 DEBUG : subdir/file20.txt: size = 0 OK 2026/07/27 06:26:01 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:26:01 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:26:01 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:26:01 DEBUG : subdir/file20.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:26:01 INFO : subdir/file20.txt: Copied (new) 2026/07/27 06:26:01 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/07/27 06:26:01 DEBUG : subdir/RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:26:01 INFO : subdir/RCLONE_TEST: Copied (new) 2026/07/27 06:26:01 NOTICE: checking path2 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2' 2026/07/27 06:26:02 NOTICE: (01) : test check-access 2026/07/27 06:26:02 NOTICE: (02) : test initial bisync 2026/07/27 06:26:02 NOTICE: (03) : bisync resync 2026/07/27 06:26:02 DEBUG : Reset feature "Copy" 2026/07/27 06:26:02 DEBUG : Reset feature "Copy" 2026/07/27 06:26:02 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:03 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:03 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:26:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:26:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:07 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:08 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:26:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:09 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:12 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:26:12 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:26:12 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:12 INFO : Copying Path2 files to Path1 2026/07/27 06:26:12 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:26:12 INFO : There was nothing to transfer 2026/07/27 06:26:12 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:26:13 INFO : There was nothing to transfer 2026/07/27 06:26:13 INFO : Resync updating listings 2026/07/27 06:26:13 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:13 INFO : Bisync successful 2026/07/27 06:26:13 NOTICE: (04) : test 1. see that check-access passes with the initial setup 2026/07/27 06:26:13 NOTICE: (05) : bisync check-access 2026/07/27 06:26:13 DEBUG : Reset feature "Copy" 2026/07/27 06:26:13 DEBUG : Reset feature "Copy" 2026/07/27 06:26:13 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:14 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:14 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:26:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:26:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:19 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:19 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:26:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:21 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:23 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:23 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:23 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:26:23 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:26:23 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:23 INFO : Building Path1 and Path2 listings 2026/07/27 06:26:24 INFO : Path1 checking for diffs 2026/07/27 06:26:24 INFO : Path2 checking for diffs 2026/07/27 06:26:24 INFO : Checking access health 2026/07/27 06:26:24 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2026/07/27 06:26:24 INFO : No changes found 2026/07/27 06:26:24 INFO : Updating listings 2026/07/27 06:26:24 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:24 INFO : Bisync successful 2026/07/27 06:26:24 NOTICE: (06) : test 2. delete the path2 subdir RCLONE_TEST and run sync. should fail critical. 2026/07/27 06:26:24 NOTICE: (07) : delete-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/subdir/RCLONE_TEST 2026/07/27 06:26:24 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/subdir/" 2026/07/27 06:26:24 DEBUG : fs cache: renaming cache item "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/subdir/" to be canonical "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/subdir" 2026/07/27 06:26:25 INFO : RCLONE_TEST: Deleted 2026/07/27 06:26:25 NOTICE: (08) : bisync check-access 2026/07/27 06:26:25 DEBUG : Reset feature "Copy" 2026/07/27 06:26:25 DEBUG : Reset feature "Copy" 2026/07/27 06:26:26 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:26 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:26 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:26:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:26:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:31 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:31 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:32 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:26:32 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:32 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:33 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:36 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:36 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:36 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:26:36 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:26:36 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:36 INFO : Building Path1 and Path2 listings 2026/07/27 06:26:36 INFO : Path1 checking for diffs 2026/07/27 06:26:36 INFO : Path2 checking for diffs 2026/07/27 06:26:36 INFO : - Path2 File was deleted - subdir/RCLONE_TEST 2026/07/27 06:26:36 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2026/07/27 06:26:36 INFO : Checking access health 2026/07/27 06:26:36 ERROR : Access test failed: Path1 count 2, Path2 count 1 - RCLONE_TEST 2026/07/27 06:26:36 ERROR : -  Access test failed: Path1 file not found in Path2 - subdir/RCLONE_TEST 2026/07/27 06:26:36 ERROR : Bisync critical error: check file check failed 2026/07/27 06:26:36 ERROR : Bisync aborted. Must run --resync to recover. 2026/07/27 06:26:36 NOTICE: Bisync error: bisync aborted 2026/07/27 06:26:36 NOTICE: (09) : copy-listings path2-missing 2026/07/27 06:26:36 NOTICE: (10) : test 3. put the path2 subdir RCLONE_TEST back, resync. 2026/07/27 06:26:36 NOTICE: (11) : copy-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/subdir/RCLONE_TEST TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/ 2026/07/27 06:26:36 DEBUG : copyFile "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/subdir/RCLONE_TEST" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" as "" 2026/07/27 06:26:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/subdir/RCLONE_TEST" 2026/07/27 06:26:37 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:37 DEBUG : operations.CopyFile "RCLONE_TEST" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2'" as "RCLONE_TEST" 2026/07/27 06:26:37 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:26:37 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/07/27 06:26:37 DEBUG : RCLONE_TEST: Unchanged skipping 2026/07/27 06:26:37 NOTICE: (12) : bisync resync 2026/07/27 06:26:37 DEBUG : Reset feature "Copy" 2026/07/27 06:26:37 DEBUG : Reset feature "Copy" 2026/07/27 06:26:38 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:38 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:39 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:26:39 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:26:39 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:40 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:43 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:44 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:44 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:26:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:26:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:26:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:26:48 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:48 INFO : Copying Path2 files to Path1 2026/07/27 06:26:48 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:26:48 INFO : There was nothing to transfer 2026/07/27 06:26:48 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:26:52 INFO : subdir/RCLONE_TEST: Copied (new) 2026/07/27 06:26:52 INFO : Resync updating listings 2026/07/27 06:26:52 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:26:52 INFO : Bisync successful 2026/07/27 06:26:52 NOTICE: (13) : test 4. run sync with check-access. should pass. 2026/07/27 06:26:52 NOTICE: (14) : bisync check-access 2026/07/27 06:26:52 DEBUG : Reset feature "Copy" 2026/07/27 06:26:52 DEBUG : Reset feature "Copy" 2026/07/27 06:26:53 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:53 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:53 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:26:54 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:26:54 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:26:58 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:26:58 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:26:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:26:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:26:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:03 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:03 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:03 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:27:03 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:27:03 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:03 INFO : Building Path1 and Path2 listings 2026/07/27 06:27:03 INFO : Path1 checking for diffs 2026/07/27 06:27:03 INFO : Path2 checking for diffs 2026/07/27 06:27:03 INFO : Checking access health 2026/07/27 06:27:03 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2026/07/27 06:27:03 INFO : No changes found 2026/07/27 06:27:03 INFO : Updating listings 2026/07/27 06:27:03 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:03 INFO : Bisync successful 2026/07/27 06:27:03 NOTICE: (15) : test 5. delete path1 top level RCLONE_TEST, run sync. should fail critical. 2026/07/27 06:27:03 NOTICE: (16) : delete-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/RCLONE_TEST 2026/07/27 06:27:03 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" 2026/07/27 06:27:04 DEBUG : fs cache: renaming cache item "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" to be canonical "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1" 2026/07/27 06:27:04 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing uploads - please wait... 2026/07/27 06:27:04 INFO : RCLONE_TEST: Deleted 2026/07/27 06:27:04 NOTICE: (17) : bisync check-access 2026/07/27 06:27:04 DEBUG : Reset feature "Copy" 2026/07/27 06:27:04 DEBUG : Reset feature "Copy" 2026/07/27 06:27:05 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:05 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:06 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:27:06 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:27:06 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:07 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:10 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:10 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:11 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:27:11 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:27:11 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:27:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:27:15 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:15 INFO : Building Path1 and Path2 listings 2026/07/27 06:27:15 INFO : Path1 checking for diffs 2026/07/27 06:27:15 INFO : - Path1 File was deleted - RCLONE_TEST 2026/07/27 06:27:15 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2026/07/27 06:27:15 INFO : Path2 checking for diffs 2026/07/27 06:27:15 INFO : Checking access health 2026/07/27 06:27:15 ERROR : Access test failed: Path1 count 1, Path2 count 2 - RCLONE_TEST 2026/07/27 06:27:15 ERROR : -  Access test failed: Path2 file not found in Path1 - RCLONE_TEST 2026/07/27 06:27:15 ERROR : Bisync critical error: check file check failed 2026/07/27 06:27:15 ERROR : Bisync aborted. Must run --resync to recover. 2026/07/27 06:27:15 NOTICE: Bisync error: bisync aborted 2026/07/27 06:27:15 NOTICE: (18) : copy-listings path1-missing 2026/07/27 06:27:15 NOTICE: (19) : test 6. run again. should fail critical due to missing listings. 2026/07/27 06:27:15 NOTICE: (20) : bisync check-access 2026/07/27 06:27:15 DEBUG : Reset feature "Copy" 2026/07/27 06:27:15 DEBUG : Reset feature "Copy" 2026/07/27 06:27:16 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:16 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:27:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:27:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:18 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:21 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:21 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:27:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:27:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:23 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:26 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:26 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:26 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:27:26 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:27:26 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:26 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run Tip: here are the filenames we were looking for. Do they exist? Path1: /tmp/062547copuvil8/workdir/TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_check_access_path1..TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_check_access_path2.path1.lst Path2: /tmp/062547copuvil8/workdir/TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_check_access_path1..TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_check_access_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/062547copuvil8/workdir" 2026/07/27 06:27:26 ERROR : Bisync aborted. Must run --resync to recover. 2026/07/27 06:27:26 NOTICE: Bisync error: bisync aborted 2026/07/27 06:27:26 NOTICE: (21) : move-listings missing-listings 2026/07/27 06:27:26 NOTICE: (22) : test 7. run resync, which will copy the path2 top level back to path1. 2026/07/27 06:27:26 NOTICE: (23) : bisync resync 2026/07/27 06:27:26 DEBUG : Reset feature "Copy" 2026/07/27 06:27:26 DEBUG : Reset feature "Copy" 2026/07/27 06:27:26 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:26 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:27:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:27:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:32 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:32 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:32 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:27:33 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:27:33 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:34 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:36 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:36 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:36 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:27:36 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:27:36 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:36 INFO : Copying Path2 files to Path1 2026/07/27 06:27:36 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:27:40 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:27:40 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:27:40 INFO : Resync updating listings 2026/07/27 06:27:40 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:40 INFO : Bisync successful 2026/07/27 06:27:40 NOTICE: (24) : test 8. run sync with --check-access. should pass. 2026/07/27 06:27:40 NOTICE: (25) : bisync check-access 2026/07/27 06:27:40 DEBUG : Reset feature "Copy" 2026/07/27 06:27:40 DEBUG : Reset feature "Copy" 2026/07/27 06:27:41 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:42 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Adding "modtime_write_test" to batch 2026/07/27 06:27:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Batch idle for 500ms so committing 2026/07/27 06:27:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:46 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:27:47 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:27:47 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Adding "modtime_write_test" to batch 2026/07/27 06:27:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Batch idle for 500ms so committing 2026/07/27 06:27:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:27:51 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:51 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:27:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:27:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:27:51 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:51 INFO : Building Path1 and Path2 listings 2026/07/27 06:27:52 INFO : Path1 checking for diffs 2026/07/27 06:27:52 INFO : Path2 checking for diffs 2026/07/27 06:27:52 INFO : Checking access health 2026/07/27 06:27:52 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2026/07/27 06:27:52 INFO : No changes found 2026/07/27 06:27:52 INFO : Updating listings 2026/07/27 06:27:52 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/" 2026/07/27 06:27:52 INFO : Bisync successful bisync_test.go:610: TEST check_access PASSED === RUN TestBisyncRemoteRemote/resolve 2026/07/27 06:27:54 INFO : path1: Making directory 2026/07/27 06:27:54 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1" 2026/07/27 06:27:55 INFO : path2: Making directory 2026/07/27 06:27:55 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2" 2026/07/27 06:27:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial" 2026/07/27 06:27:55 DEBUG : Creating backend with remote "/tmp/062547copuvil8/initdir/test_resolve-yigavut5" 2026/07/27 06:27:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/07/27 06:27:55 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/07/27 06:27:55 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/07/27 06:27:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/07/27 06:27:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:27:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:27:55 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/07/27 06:27:55 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:27:55 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/07/27 06:27:55 INFO : file2.txt: Copied (new) 2026/07/27 06:27:55 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/07/27 06:27:55 INFO : file1.txt: Copied (new) 2026/07/27 06:27:55 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_resolve-yigavut5: Waiting for checks to finish 2026/07/27 06:27:55 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_resolve-yigavut5: Waiting for transfers to finish 2026/07/27 06:27:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles" 2026/07/27 06:27:55 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:27:55 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:55 DEBUG : file1R.txt.f5ef699a.partial: size = 33 OK 2026/07/27 06:27:55 DEBUG : file1L.txt.6455455d.partial: size = 33 OK 2026/07/27 06:27:55 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK 2026/07/27 06:27:55 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2026/07/27 06:27:55 DEBUG : file2.txt.67fcf9d0.partial: size = 37 OK 2026/07/27 06:27:55 DEBUG : file1R.txt.f5ef699a.partial: renamed to: file1R.txt 2026/07/27 06:27:55 INFO : file1R.txt: Copied (new) 2026/07/27 06:27:55 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2026/07/27 06:27:55 DEBUG : file1L.txt.6455455d.partial: renamed to: file1L.txt 2026/07/27 06:27:55 INFO : file1L.txt: Copied (new) 2026/07/27 06:27:55 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1: Waiting for checks to finish 2026/07/27 06:27:55 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1: Waiting for transfers to finish 2026/07/27 06:27:55 DEBUG : file2.txt.67fcf9d0.partial: renamed to: file2.txt 2026/07/27 06:27:55 INFO : file2.txt: Copied (new) 2026/07/27 06:27:56 ERROR : error listing: directory not found 2026/07/27 06:27:56 ERROR : error listing: directory not found 2026/07/27 06:27:56 NOTICE: checking initFs Local file system at /tmp/062547copuvil8/initdir/test_resolve-yigavut5 2026/07/27 06:27:56 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:56 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:27:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:27:56 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Waiting for checks to finish 2026/07/27 06:27:56 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Waiting for transfers to finish 2026/07/27 06:27:57 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:27:57 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:27:57 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:27:57 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:27:57 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:27:57 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:27:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:27:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file2.txt" to batch 2026/07/27 06:27:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "RCLONE_TEST" to batch 2026/07/27 06:27:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:27:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 3 starting with: file1.txt 2026/07/27 06:27:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 3 starting with: file1.txt 2026/07/27 06:27:59 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:27:59 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:27:59 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:27:59 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:27:59 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:27:59 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:27:59 INFO : file1.txt: Copied (new) 2026/07/27 06:27:59 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:27:59 INFO : file2.txt: Copied (new) 2026/07/27 06:27:59 NOTICE: checking Path1 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1' 2026/07/27 06:28:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:28:00 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:28:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:28:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Waiting for checks to finish 2026/07/27 06:28:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Waiting for transfers to finish 2026/07/27 06:28:00 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:28:00 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:28:00 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:28:00 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:28:00 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:28:01 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:28:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:28:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "RCLONE_TEST" to batch 2026/07/27 06:28:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file2.txt" to batch 2026/07/27 06:28:02 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:02 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 3 starting with: file1.txt 2026/07/27 06:28:03 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 3 starting with: file1.txt 2026/07/27 06:28:03 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:28:03 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:28:03 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:28:03 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:28:03 INFO : file1.txt: Copied (new) 2026/07/27 06:28:03 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:28:03 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:28:03 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:28:03 INFO : file2.txt: Copied (new) 2026/07/27 06:28:03 NOTICE: checking path2 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2' 2026/07/27 06:28:03 NOTICE: (01) : test resolve 2026/07/27 06:28:03 NOTICE: (02) : test initial bisync 2026/07/27 06:28:03 NOTICE: (03) : bisync resync 2026/07/27 06:28:03 DEBUG : Reset feature "Copy" 2026/07/27 06:28:03 DEBUG : Reset feature "Copy" 2026/07/27 06:28:03 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:04 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:28:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:28:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:08 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:09 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:09 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:28:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:28:13 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:28:13 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:13 INFO : Copying Path2 files to Path1 2026/07/27 06:28:13 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:28:13 INFO : There was nothing to transfer 2026/07/27 06:28:13 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:28:14 INFO : There was nothing to transfer 2026/07/27 06:28:14 INFO : Resync updating listings 2026/07/27 06:28:14 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:14 INFO : Bisync successful 2026/07/27 06:28:14 NOTICE: (04) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2026/07/27 06:28:14 NOTICE: (05) : touch-glob 2001-01-02 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:28:14 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" 2026/07/27 06:28:14 DEBUG : fs cache: renaming cache item "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" to be canonical "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:28:14 DEBUG : file1R.txt: Set modification time 2001-01-02 2026/07/27 06:28:14 NOTICE: (06) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:28:14 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:28:14 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:28:14 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:14 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:28:14 DEBUG : file1R.txt: size = 33 (Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1) 2026/07/27 06:28:14 DEBUG : file1.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2') 2026/07/27 06:28:14 DEBUG : file1R.txt: Sizes differ 2026/07/27 06:28:15 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:28:15 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:28:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:28:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:28:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:28:17 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:17 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:28:17 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:28:17 NOTICE: (07) : touch-glob 2001-03-04 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1L.txt 2026/07/27 06:28:17 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:28:17 DEBUG : file1L.txt: Set modification time 2001-03-04 2026/07/27 06:28:17 NOTICE: (08) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/ file1.txt 2026/07/27 06:28:17 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" as "file1.txt" 2026/07/27 06:28:17 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" 2026/07/27 06:28:17 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" 2026/07/27 06:28:17 DEBUG : operations.CopyFile "file1L.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1'" as "file1.txt" 2026/07/27 06:28:18 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1) 2026/07/27 06:28:18 DEBUG : file1.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1') 2026/07/27 06:28:18 DEBUG : file1L.txt: Sizes differ 2026/07/27 06:28:18 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:28:19 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:28:19 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:28:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:28:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:28:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:28:20 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:20 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:28:20 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:28:20 NOTICE: (09) : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato 2026/07/27 06:28:20 NOTICE: (10) : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato 2026/07/27 06:28:20 DEBUG : Reset feature "Copy" 2026/07/27 06:28:20 DEBUG : Reset feature "Copy" 2026/07/27 06:28:21 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:21 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:28:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:28:22 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:23 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:26 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:27 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:28:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:29 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:31 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:28:31 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:28:31 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:31 INFO : Building Path1 and Path2 listings 2026/07/27 06:28:31 INFO : Path1 checking for diffs 2026/07/27 06:28:31 INFO : - Path1 File changed: size (larger), time (newer) - file1.txt 2026/07/27 06:28:31 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2026/07/27 06:28:31 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2026/07/27 06:28:31 INFO : Path2 checking for diffs 2026/07/27 06:28:31 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2026/07/27 06:28:31 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2026/07/27 06:28:31 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2026/07/27 06:28:31 INFO : Applying changes 2026/07/27 06:28:31 INFO : Checking potential conflicts... 2026/07/27 06:28:32 ERROR : file1.txt: dropbox differ 2026/07/27 06:28:32 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 differences found 2026/07/27 06:28:32 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 errors while checking 2026/07/27 06:28:32 INFO : Finished checking the potential conflicts. 1 differences found 2026/07/27 06:28:32 NOTICE: - WARNING New or changed in both paths - file1.txt 2026/07/27 06:28:32 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 2026/07/27 06:28:32 INFO : file1.txt: The winner is: Path1 2026/07/27 06:28:32 NOTICE: - Path2 Deleting Path2 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt 2026/07/27 06:28:33 INFO : file1.txt: Deleted 2026/07/27 06:28:33 INFO : - Path1 Queue copy to Path2 - file1.txt 2026/07/27 06:28:33 INFO : - Path1 Do queued copies to - Path2 2026/07/27 06:28:36 INFO : file1.txt: Copied (new) 2026/07/27 06:28:36 INFO : Updating listings 2026/07/27 06:28:36 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:36 INFO : Bisync successful 2026/07/27 06:28:36 NOTICE: (11) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2026/07/27 06:28:36 NOTICE: (12) : touch-glob 2001-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:28:36 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:28:36 DEBUG : file1R.txt: Set modification time 2001-07-23 2026/07/27 06:28:36 NOTICE: (13) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:28:36 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:28:36 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:28:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:37 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:28:37 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:37 DEBUG : file1R.txt: Modification times differ by -3384h0m0s: 2001-07-23 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC 2026/07/27 06:28:37 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 (Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1) 2026/07/27 06:28:37 DEBUG : file1.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2') 2026/07/27 06:28:37 DEBUG : file1R.txt: dropbox differ 2026/07/27 06:28:37 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:28:38 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:28:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:28:39 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:39 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:28:40 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:28:40 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:40 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:28:40 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:28:40 NOTICE: (14) : touch-glob 2001-08-26 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1L.txt 2026/07/27 06:28:40 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:28:40 DEBUG : file1L.txt: Set modification time 2001-08-26 2026/07/27 06:28:40 NOTICE: (15) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/ file1.txt 2026/07/27 06:28:40 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" as "file1.txt" 2026/07/27 06:28:40 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" 2026/07/27 06:28:40 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" 2026/07/27 06:28:41 DEBUG : operations.CopyFile "file1L.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1'" as "file1.txt" 2026/07/27 06:28:41 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:41 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC 2026/07/27 06:28:41 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:28:41 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Forced to upload files to set modification times on this backend. 2026/07/27 06:28:41 INFO : file1.txt: src and dst identical but can't set mod time without deleting and re-uploading 2026/07/27 06:28:42 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:28:42 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:28:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:28:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:28:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:28:44 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:28:44 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:28:44 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:28:44 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:28:44 NOTICE: (16) : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur 2026/07/27 06:28:44 NOTICE: (17) : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur 2026/07/27 06:28:44 DEBUG : Reset feature "Copy" 2026/07/27 06:28:44 DEBUG : Reset feature "Copy" 2026/07/27 06:28:45 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:45 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:28:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:28:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:47 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:50 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:28:51 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:28:51 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:28:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:28:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:52 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:28:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:28:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:28:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:28:55 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:28:55 INFO : Building Path1 and Path2 listings 2026/07/27 06:28:55 INFO : Path1 checking for diffs 2026/07/27 06:28:55 INFO : - Path1 File changed: time (newer) - file1.txt 2026/07/27 06:28:55 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2026/07/27 06:28:55 INFO : (Modified:  1 newer,  0 older) 2026/07/27 06:28:55 INFO : Path2 checking for diffs 2026/07/27 06:28:55 INFO : - Path2 File changed: time (newer) - file1.txt 2026/07/27 06:28:55 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2026/07/27 06:28:55 INFO : (Modified:  1 newer,  0 older) 2026/07/27 06:28:55 INFO : Applying changes 2026/07/27 06:28:55 INFO : Checking potential conflicts... 2026/07/27 06:28:55 ERROR : file1.txt: dropbox differ 2026/07/27 06:28:55 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 differences found 2026/07/27 06:28:55 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 errors while checking 2026/07/27 06:28:55 INFO : Finished checking the potential conflicts. 1 differences found 2026/07/27 06:28:55 NOTICE: - WARNING New or changed in both paths - file1.txt 2026/07/27 06:28:55 INFO : file1.txt: The winner is: Path2 2026/07/27 06:28:55 NOTICE: - Path1 Renaming Path1 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt.dinosaur1 2026/07/27 06:28:57 INFO : file1.txt: Moved (server-side) to: file1.txt.dinosaur1 2026/07/27 06:28:57 NOTICE: - Path1 Queue copy to Path2 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt.dinosaur1 2026/07/27 06:28:57 NOTICE: - Path2 Not renaming Path2 copy, as it was determined the winner - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt 2026/07/27 06:28:57 NOTICE: - Path2 Queue copy to Path1 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt 2026/07/27 06:28:57 INFO : - Path2 Do queued copies to - Path1 2026/07/27 06:29:00 INFO : file1.txt: Copied (new) 2026/07/27 06:29:00 INFO : - Path1 Do queued copies to - Path2 2026/07/27 06:29:04 INFO : file1.txt.dinosaur1: Copied (new) 2026/07/27 06:29:04 INFO : Updating listings 2026/07/27 06:29:04 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:04 INFO : Bisync successful 2026/07/27 06:29:04 NOTICE: (18) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2026/07/27 06:29:04 NOTICE: (19) : touch-glob 2002-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:29:04 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:29:04 DEBUG : file1R.txt: Set modification time 2002-07-23 2026/07/27 06:29:04 NOTICE: (20) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:29:04 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:29:04 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:29:04 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:04 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:29:04 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:04 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC 2026/07/27 06:29:04 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:29:04 INFO : file1.txt: src and dst identical but can't set mod time without deleting and re-uploading 2026/07/27 06:29:05 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:29:06 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:29:07 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:29:07 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:29:07 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:29:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:29:08 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:08 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:29:08 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:29:08 NOTICE: (21) : touch-glob 2002-08-26 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1L.txt 2026/07/27 06:29:08 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:29:08 DEBUG : file1L.txt: Set modification time 2002-08-26 2026/07/27 06:29:08 NOTICE: (22) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/ file1.txt 2026/07/27 06:29:08 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" as "file1.txt" 2026/07/27 06:29:08 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" 2026/07/27 06:29:08 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" 2026/07/27 06:29:08 DEBUG : operations.CopyFile "file1L.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1'" as "file1.txt" 2026/07/27 06:29:08 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:08 DEBUG : file1L.txt: Modification times differ by -9576h0m0s: 2002-08-26 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC 2026/07/27 06:29:08 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d (Local file system at /tmp/062547copuvil8/datadir/test_resolve-rewepay1) 2026/07/27 06:29:08 DEBUG : file1.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1') 2026/07/27 06:29:08 DEBUG : file1L.txt: dropbox differ 2026/07/27 06:29:09 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:29:10 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:29:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:29:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:29:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:29:11 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:29:11 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:11 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:29:11 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2026/07/27 06:29:11 NOTICE: (23) : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple 2026/07/27 06:29:11 NOTICE: (24) : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple 2026/07/27 06:29:11 DEBUG : Reset feature "Copy" 2026/07/27 06:29:11 DEBUG : Reset feature "Copy" 2026/07/27 06:29:12 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:29:12 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:29:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:29:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:29:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:14 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:17 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:29:17 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:29:18 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:29:18 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:29:18 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:19 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:21 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:29:21 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:29:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:29:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:29:21 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:21 INFO : Building Path1 and Path2 listings 2026/07/27 06:29:22 INFO : Path1 checking for diffs 2026/07/27 06:29:22 INFO : - Path1 File is new - file1.txt 2026/07/27 06:29:22 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:29:22 INFO : Path2 checking for diffs 2026/07/27 06:29:22 INFO : - Path2 File is new - file1.txt 2026/07/27 06:29:22 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:29:22 INFO : Applying changes 2026/07/27 06:29:22 INFO : Checking potential conflicts... 2026/07/27 06:29:22 ERROR : file1.txt: dropbox differ 2026/07/27 06:29:22 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 differences found 2026/07/27 06:29:22 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 errors while checking 2026/07/27 06:29:22 INFO : Finished checking the potential conflicts. 1 differences found 2026/07/27 06:29:22 NOTICE: - WARNING New or changed in both paths - file1.txt 2026/07/27 06:29:22 INFO : file1.txt: Winner cannot be determined as sizes are equal. Path1: 33, Path2: 33, Difference: 0 2026/07/27 06:29:22 INFO : file1.txt: A winner could not be determined. 2026/07/27 06:29:22 NOTICE: - Path1 Renaming Path1 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt.apple1 2026/07/27 06:29:24 INFO : file1.txt: Moved (server-side) to: file1.txt.apple1 2026/07/27 06:29:24 NOTICE: - Path1 Queue copy to Path2 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt.apple1 2026/07/27 06:29:24 NOTICE: - Path2 Renaming Path2 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt.apple2 2026/07/27 06:29:25 INFO : file1.txt: Moved (server-side) to: file1.txt.apple2 2026/07/27 06:29:25 NOTICE: - Path2 Queue copy to Path1 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt.apple2 2026/07/27 06:29:25 INFO : - Path2 Do queued copies to - Path1 2026/07/27 06:29:29 INFO : file1.txt.apple2: Copied (new) 2026/07/27 06:29:29 INFO : - Path1 Do queued copies to - Path2 2026/07/27 06:29:32 INFO : file1.txt.apple1: Copied (new) 2026/07/27 06:29:32 INFO : Updating listings 2026/07/27 06:29:32 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:32 INFO : Bisync successful 2026/07/27 06:29:32 NOTICE: (25) : test different suffixes 2026/07/27 06:29:32 NOTICE: (26) : touch-glob 2003-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:29:32 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:29:32 DEBUG : file1R.txt: Set modification time 2003-07-23 2026/07/27 06:29:32 NOTICE: (27) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:29:32 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:29:32 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:29:32 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:33 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:29:33 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2026/07/27 06:29:33 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:29:34 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:29:34 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:29:35 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:29:35 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:29:35 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:29:35 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:35 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:29:35 INFO : file1R.txt: Copied (new) to: file1.txt 2026/07/27 06:29:35 NOTICE: (28) : touch-glob 2003-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1L.txt 2026/07/27 06:29:35 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:29:35 DEBUG : file1L.txt: Set modification time 2003-07-23 2026/07/27 06:29:35 NOTICE: (29) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/ file1.txt 2026/07/27 06:29:35 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" as "file1.txt" 2026/07/27 06:29:35 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" 2026/07/27 06:29:35 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" 2026/07/27 06:29:36 DEBUG : operations.CopyFile "file1L.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1'" as "file1.txt" 2026/07/27 06:29:36 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2026/07/27 06:29:36 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:29:37 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:29:37 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:29:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:29:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:29:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:29:38 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:29:38 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:29:38 INFO : file1L.txt: Copied (new) to: file1.txt 2026/07/27 06:29:38 NOTICE: (30) : test bisync run with --conflict-resolve=older --conflict-loser=num --conflict-suffix=cloud,local 2026/07/27 06:29:38 NOTICE: (31) : bisync conflict-resolve=older conflict-loser=num conflict-suffix=cloud,local 2026/07/27 06:29:38 DEBUG : Reset feature "Copy" 2026/07/27 06:29:38 DEBUG : Reset feature "Copy" 2026/07/27 06:29:39 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:29:39 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:29:40 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:29:40 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:29:40 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:41 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:44 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:29:44 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:29:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:29:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:29:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:29:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:29:48 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:29:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:29:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:29:48 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:48 INFO : Building Path1 and Path2 listings 2026/07/27 06:29:49 INFO : Path1 checking for diffs 2026/07/27 06:29:49 INFO : - Path1 File is new - file1.txt 2026/07/27 06:29:49 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:29:49 INFO : Path2 checking for diffs 2026/07/27 06:29:49 INFO : - Path2 File is new - file1.txt 2026/07/27 06:29:49 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:29:49 INFO : Applying changes 2026/07/27 06:29:49 INFO : Checking potential conflicts... 2026/07/27 06:29:49 ERROR : file1.txt: dropbox differ 2026/07/27 06:29:49 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 differences found 2026/07/27 06:29:49 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 errors while checking 2026/07/27 06:29:49 INFO : Finished checking the potential conflicts. 1 differences found 2026/07/27 06:29:49 NOTICE: - WARNING New or changed in both paths - file1.txt 2026/07/27 06:29:49 INFO : file1.txt: Winner cannot be determined as times are equal. Path1: 2003-07-23 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 0s 2026/07/27 06:29:49 INFO : file1.txt: A winner could not be determined. 2026/07/27 06:29:49 NOTICE: - Path1 Renaming Path1 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt.cloud1 2026/07/27 06:29:51 INFO : file1.txt: Moved (server-side) to: file1.txt.cloud1 2026/07/27 06:29:51 NOTICE: - Path1 Queue copy to Path2 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt.cloud1 2026/07/27 06:29:51 NOTICE: - Path2 Renaming Path2 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt.local1 2026/07/27 06:29:52 INFO : file1.txt: Moved (server-side) to: file1.txt.local1 2026/07/27 06:29:52 NOTICE: - Path2 Queue copy to Path1 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt.local1 2026/07/27 06:29:52 INFO : - Path2 Do queued copies to - Path1 2026/07/27 06:29:56 INFO : file1.txt.local1: Copied (new) 2026/07/27 06:29:56 INFO : - Path1 Do queued copies to - Path2 2026/07/27 06:29:59 INFO : file1.txt.cloud1: Copied (new) 2026/07/27 06:29:59 INFO : Updating listings 2026/07/27 06:29:59 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:29:59 INFO : Bisync successful 2026/07/27 06:29:59 NOTICE: (32) : test legacy 2026/07/27 06:29:59 NOTICE: (33) : touch-glob 2004-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:29:59 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:29:59 DEBUG : file1R.txt: Set modification time 2004-07-23 2026/07/27 06:29:59 NOTICE: (34) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:29:59 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:29:59 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:29:59 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:30:00 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:30:00 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:00 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:30:01 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:30:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:30:02 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:30:02 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:30:03 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:30:03 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:30:03 DEBUG : file1R.txt: dropbox = 0500d00b622cae1ab1f40f32345fb64505c04c45f44b2a5300d751434cdb2b11 OK 2026/07/27 06:30:03 INFO : file1R.txt: Copied (new) to: file1.txt 2026/07/27 06:30:03 NOTICE: (35) : touch-glob 2004-07-23 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1L.txt 2026/07/27 06:30:03 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:30:03 DEBUG : file1L.txt: Set modification time 2004-07-23 2026/07/27 06:30:03 NOTICE: (36) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/ file1.txt 2026/07/27 06:30:03 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" as "file1.txt" 2026/07/27 06:30:03 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1L.txt" 2026/07/27 06:30:03 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" 2026/07/27 06:30:03 DEBUG : operations.CopyFile "file1L.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1'" as "file1.txt" 2026/07/27 06:30:03 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:04 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:30:04 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:30:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "file1.txt" to batch 2026/07/27 06:30:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:30:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:30:06 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: file1.txt 2026/07/27 06:30:06 DEBUG : file1.txt: size = 33 OK 2026/07/27 06:30:06 DEBUG : file1L.txt: dropbox = ae041ab5cc3f84ecc4bccfc255f0eb7e64a9471b8c9aedf8fdd3f8428d6cd99d OK 2026/07/27 06:30:06 INFO : file1L.txt: Copied (new) to: file1.txt 2026/07/27 06:30:06 NOTICE: (37) : test bisync run with --conflict-resolve=none --conflict-loser=pathname --conflict-suffix=.path 2026/07/27 06:30:06 NOTICE: (38) : bisync conflict-resolve=none conflict-loser=pathname conflict-suffix=.path 2026/07/27 06:30:06 DEBUG : Reset feature "Copy" 2026/07/27 06:30:06 DEBUG : Reset feature "Copy" 2026/07/27 06:30:06 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:30:07 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:30:07 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Adding "modtime_write_test" to batch 2026/07/27 06:30:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Batch idle for 500ms so committing 2026/07/27 06:30:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:30:08 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:30:11 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:30:12 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:30:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "modtime_write_test" to batch 2026/07/27 06:30:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:30:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:30:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:30:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:30:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:30:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:30:16 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:30:16 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:30:16 INFO : Building Path1 and Path2 listings 2026/07/27 06:30:16 INFO : Path1 checking for diffs 2026/07/27 06:30:16 INFO : - Path1 File is new - file1.txt 2026/07/27 06:30:16 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:30:16 INFO : Path2 checking for diffs 2026/07/27 06:30:16 INFO : - Path2 File is new - file1.txt 2026/07/27 06:30:16 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2026/07/27 06:30:16 INFO : Applying changes 2026/07/27 06:30:16 INFO : Checking potential conflicts... 2026/07/27 06:30:17 ERROR : file1.txt: dropbox differ 2026/07/27 06:30:17 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 differences found 2026/07/27 06:30:17 NOTICE: Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': 1 errors while checking 2026/07/27 06:30:17 INFO : Finished checking the potential conflicts. 1 differences found 2026/07/27 06:30:17 NOTICE: - WARNING New or changed in both paths - file1.txt 2026/07/27 06:30:17 NOTICE: - Path1 Renaming Path1 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt..path1 2026/07/27 06:30:18 INFO : file1.txt: Moved (server-side) to: file1.txt..path1 2026/07/27 06:30:18 NOTICE: - Path1 Queue copy to Path2 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt..path1 2026/07/27 06:30:18 NOTICE: - Path2 Renaming Path2 copy - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/file1.txt..path2 2026/07/27 06:30:20 INFO : file1.txt: Moved (server-side) to: file1.txt..path2 2026/07/27 06:30:20 NOTICE: - Path2 Queue copy to Path1 - TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/file1.txt..path2 2026/07/27 06:30:20 INFO : - Path2 Do queued copies to - Path1 2026/07/27 06:30:23 INFO : file1.txt..path2: Copied (new) 2026/07/27 06:30:23 INFO : - Path1 Do queued copies to - Path2 2026/07/27 06:30:27 INFO : file1.txt..path1: Copied (new) 2026/07/27 06:30:27 INFO : Updating listings 2026/07/27 06:30:27 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:30:27 INFO : Bisync successful 2026/07/27 06:30:27 NOTICE: (39) : test deletes on both sides with default suffix 2026/07/27 06:30:27 NOTICE: (40) : touch-glob 2005-01-02 /tmp/062547copuvil8/datadir/test_resolve-rewepay1/ file1R.txt 2026/07/27 06:30:27 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/" for canonical name "/tmp/062547copuvil8/datadir/test_resolve-rewepay1" 2026/07/27 06:30:27 DEBUG : file1R.txt: Set modification time 2005-01-02 2026/07/27 06:30:27 NOTICE: (41) : copy-as /tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/ file1.txt 2026/07/27 06:30:27 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" as "file1.txt" 2026/07/27 06:30:27 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resolve-rewepay1/file1R.txt" 2026/07/27 06:30:27 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2/" 2026/07/27 06:30:27 DEBUG : operations.CopyFile "file1R.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2'" as "file1.txt" 2026/07/27 06:30:28 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:28 DEBUG : file1.txt: Uploading chunk 1/1 2026/07/27 06:30:29 DEBUG : file1.txt: Uploading chunk 2/1 2026/07/27 06:30:29 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Adding "file1.txt" to batch 2026/07/27 06:30:30 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Batch idle for 500ms so committing 2026/07/27 06:30:30 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing sync batch length 1 starting with: file1.txt 2026/07/27 06:30:32 ERROR : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations 2026/07/27 06:30:32 ERROR : file1R.txt: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations bisync_test.go:545: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:545 /usr/local/go/src/strings/iter.go:54 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:527 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:407 Error: test step failed Test: TestBisyncRemoteRemote/resolve Messages: step 41 failed: upload failed: batch upload failed: upload failed: too_many_write_operations === RUN TestBisyncRemoteRemote/resync 2026/07/27 06:30:32 INFO : path1: Making directory 2026/07/27 06:30:32 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1" 2026/07/27 06:30:32 DEBUG : Config file has changed externally - reloading 2026/07/27 06:30:32 INFO : path2: Making directory 2026/07/27 06:30:32 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2" 2026/07/27 06:30:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial" 2026/07/27 06:30:33 DEBUG : Creating backend with remote "/tmp/062547copuvil8/initdir/test_resync-kavanuj6" 2026/07/27 06:30:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_resync-kavanuj6: Waiting for checks to finish 2026/07/27 06:30:33 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/07/27 06:30:33 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/07/27 06:30:33 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : Local file system at /tmp/062547copuvil8/initdir/test_resync-kavanuj6: Waiting for transfers to finish 2026/07/27 06:30:33 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/07/27 06:30:33 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:30:33 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/07/27 06:30:33 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/07/27 06:30:33 INFO : file1.txt: Copied (new) 2026/07/27 06:30:33 INFO : file2.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/07/27 06:30:33 INFO : file3.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/07/27 06:30:33 INFO : file4.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/07/27 06:30:33 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/07/27 06:30:33 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/07/27 06:30:33 INFO : file6.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/07/27 06:30:33 INFO : file5.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/27 06:30:33 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/07/27 06:30:33 INFO : file7.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles" 2026/07/27 06:30:33 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8" 2026/07/27 06:30:33 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:33 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8: Waiting for checks to finish 2026/07/27 06:30:33 DEBUG : Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8: Waiting for transfers to finish 2026/07/27 06:30:33 DEBUG : fileB.txt.b8ec97e6.partial: size = 19 OK 2026/07/27 06:30:33 DEBUG : fileA.txt.04c13af3.partial: size = 19 OK 2026/07/27 06:30:33 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/07/27 06:30:33 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/07/27 06:30:33 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt 2026/07/27 06:30:33 INFO : fileA.txt: Copied (new) 2026/07/27 06:30:33 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt 2026/07/27 06:30:33 INFO : fileB.txt: Copied (new) 2026/07/27 06:30:33 NOTICE: Error too_many_requests/... Too many requests or write operations. Trying again in 10 seconds. 2026/07/27 06:30:33 DEBUG : pacer: low level retry 1/10 (error too_many_requests/..: trying again in 10s) 2026/07/27 06:30:33 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/07/27 06:30:33 DEBUG : pacer: Reducing sleep to 7.5s 2026/07/27 06:30:43 DEBUG : pacer: Reducing sleep to 5.625s 2026/07/27 06:30:43 ERROR : error listing: directory not found 2026/07/27 06:30:43 ERROR : error listing: directory not found 2026/07/27 06:30:43 NOTICE: checking initFs Local file system at /tmp/062547copuvil8/initdir/test_resync-kavanuj6 2026/07/27 06:30:51 DEBUG : pacer: Reducing sleep to 4.21875s 2026/07/27 06:30:51 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:30:51 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Waiting for checks to finish 2026/07/27 06:30:51 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Waiting for transfers to finish 2026/07/27 06:30:57 DEBUG : pacer: Reducing sleep to 3.1640625s 2026/07/27 06:30:57 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:31:01 DEBUG : pacer: Reducing sleep to 2.373046875s 2026/07/27 06:31:01 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:31:04 DEBUG : pacer: Reducing sleep to 1.779785156s 2026/07/27 06:31:04 DEBUG : file3.txt: Uploading chunk 1/0 2026/07/27 06:31:06 DEBUG : pacer: Reducing sleep to 1.334838867s 2026/07/27 06:31:06 DEBUG : file4.txt: Uploading chunk 1/0 2026/07/27 06:31:08 DEBUG : pacer: Reducing sleep to 1.00112915s 2026/07/27 06:31:08 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:31:09 DEBUG : pacer: Reducing sleep to 750.846862ms 2026/07/27 06:31:09 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:31:10 DEBUG : pacer: Reducing sleep to 563.135146ms 2026/07/27 06:31:10 DEBUG : file3.txt: Uploading chunk 2/0 2026/07/27 06:31:11 DEBUG : pacer: Reducing sleep to 422.351359ms 2026/07/27 06:31:11 DEBUG : file4.txt: Uploading chunk 2/0 2026/07/27 06:31:12 DEBUG : pacer: Reducing sleep to 316.763519ms 2026/07/27 06:31:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file1.txt" to batch 2026/07/27 06:31:12 DEBUG : pacer: Reducing sleep to 237.572639ms 2026/07/27 06:31:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file2.txt" to batch 2026/07/27 06:31:12 DEBUG : pacer: Reducing sleep to 178.179479ms 2026/07/27 06:31:12 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file3.txt" to batch 2026/07/27 06:31:13 DEBUG : pacer: Reducing sleep to 133.634609ms 2026/07/27 06:31:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file4.txt" to batch 2026/07/27 06:31:13 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 4 starting with: file1.txt 2026/07/27 06:31:14 DEBUG : pacer: Reducing sleep to 100.225956ms 2026/07/27 06:31:14 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 4 starting with: file1.txt 2026/07/27 06:31:14 DEBUG : file4.txt: size = 0 OK 2026/07/27 06:31:14 DEBUG : file3.txt: size = 0 OK 2026/07/27 06:31:14 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:31:14 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:14 DEBUG : file4.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:14 INFO : file1.txt: Copied (new) 2026/07/27 06:31:14 INFO : file4.txt: Copied (new) 2026/07/27 06:31:14 DEBUG : file3.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:14 INFO : file3.txt: Copied (new) 2026/07/27 06:31:14 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:31:14 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:14 INFO : file2.txt: Copied (new) 2026/07/27 06:31:14 DEBUG : pacer: Reducing sleep to 75.169467ms 2026/07/27 06:31:14 DEBUG : file6.txt: Uploading chunk 1/0 2026/07/27 06:31:14 DEBUG : pacer: Reducing sleep to 56.3771ms 2026/07/27 06:31:14 DEBUG : file5.txt: Uploading chunk 1/0 2026/07/27 06:31:14 DEBUG : pacer: Reducing sleep to 42.282825ms 2026/07/27 06:31:15 DEBUG : file7.txt: Uploading chunk 1/0 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 31.712118ms 2026/07/27 06:31:15 DEBUG : file6.txt: Uploading chunk 2/0 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 23.784088ms 2026/07/27 06:31:15 DEBUG : file5.txt: Uploading chunk 2/0 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 17.838066ms 2026/07/27 06:31:15 DEBUG : file7.txt: Uploading chunk 2/0 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 13.378549ms 2026/07/27 06:31:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file6.txt" to batch 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 10.033911ms 2026/07/27 06:31:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file5.txt" to batch 2026/07/27 06:31:15 DEBUG : pacer: Reducing sleep to 10ms 2026/07/27 06:31:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file7.txt" to batch 2026/07/27 06:31:16 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:31:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:31:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 3 starting with: file6.txt 2026/07/27 06:31:16 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:31:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 3 starting with: file6.txt 2026/07/27 06:31:17 DEBUG : file5.txt: size = 0 OK 2026/07/27 06:31:17 DEBUG : file7.txt: size = 0 OK 2026/07/27 06:31:17 DEBUG : file6.txt: size = 0 OK 2026/07/27 06:31:17 DEBUG : file7.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:17 DEBUG : file5.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:17 INFO : file7.txt: Copied (new) 2026/07/27 06:31:17 DEBUG : file6.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:17 INFO : file5.txt: Copied (new) 2026/07/27 06:31:17 INFO : file6.txt: Copied (new) 2026/07/27 06:31:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "RCLONE_TEST" to batch 2026/07/27 06:31:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:31:17 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: RCLONE_TEST 2026/07/27 06:31:19 ERROR : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations 2026/07/27 06:31:19 ERROR : RCLONE_TEST: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations bisync_test.go:2015: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:2015 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:407 Error: Received unexpected error: upload failed: batch upload failed: upload failed: too_many_write_operations Test: TestBisyncRemoteRemote/resync Messages: setting up path1 2026/07/27 06:31:19 NOTICE: checking Path1 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:510 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:407 Error: Should be true Test: TestBisyncRemoteRemote/resync Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0) got file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0) fstest.go:203: Not found "RCLONE_TEST" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:510 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:407 Error: Not equal: expected: 0 actual : 1 Test: TestBisyncRemoteRemote/resync Messages: 1 objects not found 2026/07/27 06:31:27 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/07/27 06:31:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Waiting for checks to finish 2026/07/27 06:31:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Waiting for transfers to finish 2026/07/27 06:31:28 DEBUG : file3.txt: Uploading chunk 1/0 2026/07/27 06:31:28 DEBUG : file1.txt: Uploading chunk 1/0 2026/07/27 06:31:28 DEBUG : file4.txt: Uploading chunk 1/0 2026/07/27 06:31:28 DEBUG : file2.txt: Uploading chunk 1/0 2026/07/27 06:31:28 DEBUG : file3.txt: Uploading chunk 2/0 2026/07/27 06:31:28 DEBUG : file2.txt: Uploading chunk 2/0 2026/07/27 06:31:28 DEBUG : file4.txt: Uploading chunk 2/0 2026/07/27 06:31:28 DEBUG : file1.txt: Uploading chunk 2/0 2026/07/27 06:31:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file2.txt" to batch 2026/07/27 06:31:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file1.txt" to batch 2026/07/27 06:31:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file3.txt" to batch 2026/07/27 06:31:29 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file4.txt" to batch 2026/07/27 06:31:29 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 4 starting with: file2.txt 2026/07/27 06:31:29 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 4 starting with: file2.txt 2026/07/27 06:31:29 DEBUG : file4.txt: size = 0 OK 2026/07/27 06:31:29 DEBUG : file3.txt: size = 0 OK 2026/07/27 06:31:29 DEBUG : file1.txt: size = 0 OK 2026/07/27 06:31:29 DEBUG : file2.txt: size = 0 OK 2026/07/27 06:31:29 DEBUG : file4.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:29 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:29 INFO : file4.txt: Copied (new) 2026/07/27 06:31:29 INFO : file1.txt: Copied (new) 2026/07/27 06:31:29 DEBUG : file3.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:29 INFO : file3.txt: Copied (new) 2026/07/27 06:31:29 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:29 INFO : file2.txt: Copied (new) 2026/07/27 06:31:30 DEBUG : file7.txt: Uploading chunk 1/0 2026/07/27 06:31:30 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2026/07/27 06:31:30 DEBUG : file6.txt: Uploading chunk 1/0 2026/07/27 06:31:30 DEBUG : file5.txt: Uploading chunk 1/0 2026/07/27 06:31:30 DEBUG : file7.txt: Uploading chunk 2/0 2026/07/27 06:31:30 DEBUG : file6.txt: Uploading chunk 2/0 2026/07/27 06:31:30 DEBUG : file5.txt: Uploading chunk 2/0 2026/07/27 06:31:30 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2026/07/27 06:31:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file6.txt" to batch 2026/07/27 06:31:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file5.txt" to batch 2026/07/27 06:31:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file7.txt" to batch 2026/07/27 06:31:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "RCLONE_TEST" to batch 2026/07/27 06:31:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 4 starting with: file6.txt 2026/07/27 06:31:32 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 4 starting with: file6.txt 2026/07/27 06:31:32 DEBUG : RCLONE_TEST: size = 109 OK 2026/07/27 06:31:32 DEBUG : file6.txt: size = 0 OK 2026/07/27 06:31:32 DEBUG : file5.txt: size = 0 OK 2026/07/27 06:31:32 DEBUG : file7.txt: size = 0 OK 2026/07/27 06:31:32 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2026/07/27 06:31:32 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:31:32 DEBUG : file6.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:32 INFO : file6.txt: Copied (new) 2026/07/27 06:31:32 DEBUG : file5.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:32 INFO : file5.txt: Copied (new) 2026/07/27 06:31:32 DEBUG : file7.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2026/07/27 06:31:32 INFO : file7.txt: Copied (new) 2026/07/27 06:31:32 NOTICE: checking path2 Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2' 2026/07/27 06:31:32 NOTICE: (01) : test resync 2026/07/27 06:31:32 NOTICE: (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2026/07/27 06:31:32 NOTICE: (03) : purge-children TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/ 2026/07/27 06:31:32 DEBUG : file1.txt: Remove file 2026/07/27 06:31:33 DEBUG : file2.txt: Remove file 2026/07/27 06:31:33 DEBUG : file3.txt: Remove file 2026/07/27 06:31:34 DEBUG : file4.txt: Remove file 2026/07/27 06:31:34 DEBUG : file5.txt: Remove file 2026/07/27 06:31:35 DEBUG : file6.txt: Remove file 2026/07/27 06:31:36 DEBUG : file7.txt: Remove file 2026/07/27 06:31:36 NOTICE: (04) : bisync resync 2026/07/27 06:31:36 DEBUG : Reset feature "Copy" 2026/07/27 06:31:36 DEBUG : Reset feature "Copy" 2026/07/27 06:31:37 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:31:37 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:31:37 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "modtime_write_test" to batch 2026/07/27 06:31:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:31:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:31:38 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:31:42 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:31:42 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:31:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "modtime_write_test" to batch 2026/07/27 06:31:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:31:43 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:31:44 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:31:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:31:46 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:31:46 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:31:46 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:31:46 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:31:46 INFO : Copying Path2 files to Path1 2026/07/27 06:31:46 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:31:47 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2/subdir': Committing uploads - please wait... 2026/07/27 06:31:47 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path2': Committing uploads - please wait... 2026/07/27 06:31:49 INFO : file2.txt: Copied (new) 2026/07/27 06:31:49 INFO : file3.txt: Copied (new) 2026/07/27 06:31:49 INFO : file4.txt: Copied (new) 2026/07/27 06:31:49 INFO : file1.txt: Copied (new) 2026/07/27 06:31:52 INFO : file7.txt: Copied (new) 2026/07/27 06:31:52 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:31:52 INFO : file5.txt: Copied (new) 2026/07/27 06:31:52 INFO : file6.txt: Copied (new) 2026/07/27 06:31:52 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:31:52 INFO : Resync updating listings 2026/07/27 06:31:52 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:31:52 INFO : Bisync successful 2026/07/27 06:31:52 NOTICE: (05) : move-listings empty-path1 2026/07/27 06:31:52 NOTICE: (06) : test 2. resync with empty path2, resulting in syncing all content to path2. 2026/07/27 06:31:52 NOTICE: (07) : purge-children TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ 2026/07/27 06:31:53 DEBUG : file1.txt: Remove file 2026/07/27 06:31:53 DEBUG : file2.txt: Remove file 2026/07/27 06:31:54 DEBUG : file3.txt: Remove file 2026/07/27 06:31:54 DEBUG : file4.txt: Remove file 2026/07/27 06:31:55 DEBUG : file5.txt: Remove file 2026/07/27 06:31:55 DEBUG : file6.txt: Remove file 2026/07/27 06:31:56 DEBUG : file7.txt: Remove file 2026/07/27 06:31:56 DEBUG : RCLONE_TEST: Remove file 2026/07/27 06:31:57 NOTICE: (08) : bisync resync 2026/07/27 06:31:57 DEBUG : Reset feature "Copy" 2026/07/27 06:31:57 DEBUG : Reset feature "Copy" 2026/07/27 06:31:57 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:31:58 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:31:58 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "modtime_write_test" to batch 2026/07/27 06:31:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:31:59 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:03 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:32:04 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:32:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "modtime_write_test" to batch 2026/07/27 06:32:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:05 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:06 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:09 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:32:09 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:32:09 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:32:09 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:32:09 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:09 INFO : Copying Path2 files to Path1 2026/07/27 06:32:09 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:32:09 INFO : There was nothing to transfer 2026/07/27 06:32:09 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:32:12 INFO : file2.txt: Copied (new) 2026/07/27 06:32:12 INFO : file1.txt: Copied (new) 2026/07/27 06:32:12 INFO : file4.txt: Copied (new) 2026/07/27 06:32:12 INFO : file3.txt: Copied (new) 2026/07/27 06:32:15 INFO : file7.txt: Copied (new) 2026/07/27 06:32:15 INFO : file6.txt: Copied (new) 2026/07/27 06:32:15 INFO : file5.txt: Copied (new) 2026/07/27 06:32:15 INFO : RCLONE_TEST: Copied (new) 2026/07/27 06:32:15 INFO : Resync updating listings 2026/07/27 06:32:15 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:15 INFO : Bisync successful 2026/07/27 06:32:15 NOTICE: (09) : move-listings empty-path2 2026/07/27 06:32:15 NOTICE: (10) : test 3. exercise all of the various file difference scenarios during a resync. 2026/07/27 06:32:15 NOTICE: (11) : touch-glob 2002-02-02 /tmp/062547copuvil8/datadir/test_resync-cayubuy8/ fileA.txt 2026/07/27 06:32:15 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/" 2026/07/27 06:32:15 DEBUG : Config file has changed externally - reloading 2026/07/27 06:32:15 DEBUG : fs cache: renaming cache item "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/" to be canonical "/tmp/062547copuvil8/datadir/test_resync-cayubuy8" 2026/07/27 06:32:15 DEBUG : fileA.txt: Set modification time 2002-02-02 2026/07/27 06:32:15 NOTICE: (12) : touch-glob 1999-09-09 /tmp/062547copuvil8/datadir/test_resync-cayubuy8/ fileB.txt 2026/07/27 06:32:15 DEBUG : fs cache: switching user supplied name "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/" for canonical name "/tmp/062547copuvil8/datadir/test_resync-cayubuy8" 2026/07/27 06:32:15 DEBUG : fileB.txt: Set modification time 1999-09-09 2026/07/27 06:32:15 NOTICE: (13) : test = file - path1 - path2 - expected action - who wins 2026/07/27 06:32:15 NOTICE: (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2026/07/27 06:32:15 NOTICE: (15) : delete-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/file1.txt 2026/07/27 06:32:15 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:15 DEBUG : fs cache: renaming cache item "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" to be canonical "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2" 2026/07/27 06:32:15 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing uploads - please wait... 2026/07/27 06:32:16 INFO : file1.txt: Deleted 2026/07/27 06:32:16 NOTICE: (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2026/07/27 06:32:16 NOTICE: (17) : delete-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/file2.txt 2026/07/27 06:32:16 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" 2026/07/27 06:32:16 DEBUG : fs cache: renaming cache item "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" to be canonical "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1" 2026/07/27 06:32:16 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing uploads - please wait... 2026/07/27 06:32:17 INFO : file2.txt: Deleted 2026/07/27 06:32:17 NOTICE: (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2026/07/27 06:32:17 NOTICE: (19) : copy-as /tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ file3.txt 2026/07/27 06:32:17 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" as "file3.txt" 2026/07/27 06:32:17 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" 2026/07/27 06:32:17 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:17 DEBUG : operations.CopyFile "fileA.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2'" as "file3.txt" 2026/07/27 06:32:18 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8) 2026/07/27 06:32:18 DEBUG : file3.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2') 2026/07/27 06:32:18 DEBUG : fileA.txt: Sizes differ 2026/07/27 06:32:18 DEBUG : file3.txt: Uploading chunk 1/1 2026/07/27 06:32:19 DEBUG : file3.txt: Uploading chunk 2/1 2026/07/27 06:32:19 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file3.txt" to batch 2026/07/27 06:32:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: file3.txt 2026/07/27 06:32:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: file3.txt 2026/07/27 06:32:20 DEBUG : file3.txt: size = 19 OK 2026/07/27 06:32:20 DEBUG : fileA.txt: dropbox = 7400df2260f021cfd643bc1695434ee8bfd86daf687981b5d58eb57a80a4dd50 OK 2026/07/27 06:32:20 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2026/07/27 06:32:20 NOTICE: (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2026/07/27 06:32:20 NOTICE: (21) : delete-file TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/file4.txt 2026/07/27 06:32:20 DEBUG : fs cache: switching user supplied name "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" for canonical name "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1" 2026/07/27 06:32:21 INFO : file4.txt: Deleted 2026/07/27 06:32:21 NOTICE: (22) : copy-as /tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ file4.txt 2026/07/27 06:32:21 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" as "file4.txt" 2026/07/27 06:32:21 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" 2026/07/27 06:32:21 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:21 DEBUG : operations.CopyFile "fileA.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2'" as "file4.txt" 2026/07/27 06:32:22 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8) 2026/07/27 06:32:22 DEBUG : file4.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2') 2026/07/27 06:32:22 DEBUG : fileA.txt: Sizes differ 2026/07/27 06:32:22 DEBUG : file4.txt: Uploading chunk 1/1 2026/07/27 06:32:23 DEBUG : file4.txt: Uploading chunk 2/1 2026/07/27 06:32:23 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file4.txt" to batch 2026/07/27 06:32:24 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:24 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: file4.txt 2026/07/27 06:32:25 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: file4.txt 2026/07/27 06:32:25 DEBUG : file4.txt: size = 19 OK 2026/07/27 06:32:25 DEBUG : fileA.txt: dropbox = 7400df2260f021cfd643bc1695434ee8bfd86daf687981b5d58eb57a80a4dd50 OK 2026/07/27 06:32:25 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2026/07/27 06:32:25 NOTICE: (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2026/07/27 06:32:25 NOTICE: (24) : copy-as /tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ file5.txt 2026/07/27 06:32:25 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" as "file5.txt" 2026/07/27 06:32:25 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt" 2026/07/27 06:32:25 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:25 DEBUG : operations.CopyFile "fileB.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2'" as "file5.txt" 2026/07/27 06:32:25 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8) 2026/07/27 06:32:25 DEBUG : file5.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2') 2026/07/27 06:32:25 DEBUG : fileB.txt: Sizes differ 2026/07/27 06:32:26 DEBUG : file5.txt: Uploading chunk 1/1 2026/07/27 06:32:26 DEBUG : file5.txt: Uploading chunk 2/1 2026/07/27 06:32:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file5.txt" to batch 2026/07/27 06:32:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:27 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: file5.txt 2026/07/27 06:32:28 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: file5.txt 2026/07/27 06:32:28 DEBUG : file5.txt: size = 19 OK 2026/07/27 06:32:28 DEBUG : fileB.txt: dropbox = 7400df2260f021cfd643bc1695434ee8bfd86daf687981b5d58eb57a80a4dd50 OK 2026/07/27 06:32:28 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2026/07/27 06:32:28 NOTICE: (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2026/07/27 06:32:28 NOTICE: (26) : copy-as /tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/ file6.txt 2026/07/27 06:32:28 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" as "file6.txt" 2026/07/27 06:32:28 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileB.txt" 2026/07/27 06:32:28 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" 2026/07/27 06:32:28 DEBUG : operations.CopyFile "fileB.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1'" as "file6.txt" 2026/07/27 06:32:28 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8) 2026/07/27 06:32:28 DEBUG : file6.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1') 2026/07/27 06:32:28 DEBUG : fileB.txt: Sizes differ 2026/07/27 06:32:29 DEBUG : file6.txt: Uploading chunk 1/1 2026/07/27 06:32:29 DEBUG : file6.txt: Uploading chunk 2/1 2026/07/27 06:32:30 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "file6.txt" to batch 2026/07/27 06:32:30 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:32:30 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: file6.txt 2026/07/27 06:32:31 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: file6.txt 2026/07/27 06:32:31 DEBUG : file6.txt: size = 19 OK 2026/07/27 06:32:31 DEBUG : fileB.txt: dropbox = 7400df2260f021cfd643bc1695434ee8bfd86daf687981b5d58eb57a80a4dd50 OK 2026/07/27 06:32:31 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2026/07/27 06:32:31 NOTICE: (27) : copy-as /tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ file6.txt 2026/07/27 06:32:31 DEBUG : copyFile "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" to "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" as "file6.txt" 2026/07/27 06:32:31 DEBUG : Creating backend with remote "/tmp/062547copuvil8/datadir/test_resync-cayubuy8/fileA.txt" 2026/07/27 06:32:31 DEBUG : Creating backend with remote "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:31 DEBUG : operations.CopyFile "fileA.txt" to "Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2'" as "file6.txt" 2026/07/27 06:32:32 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/062547copuvil8/datadir/test_resync-cayubuy8) 2026/07/27 06:32:32 DEBUG : file6.txt: size = 0 (Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2') 2026/07/27 06:32:32 DEBUG : fileA.txt: Sizes differ 2026/07/27 06:32:32 DEBUG : file6.txt: Uploading chunk 1/1 2026/07/27 06:32:33 DEBUG : file6.txt: Uploading chunk 2/1 2026/07/27 06:32:33 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "file6.txt" to batch 2026/07/27 06:32:34 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:34 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: file6.txt 2026/07/27 06:32:35 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: file6.txt 2026/07/27 06:32:35 DEBUG : file6.txt: size = 19 OK 2026/07/27 06:32:35 DEBUG : fileA.txt: dropbox = 7400df2260f021cfd643bc1695434ee8bfd86daf687981b5d58eb57a80a4dd50 OK 2026/07/27 06:32:35 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2026/07/27 06:32:35 NOTICE: (28) : test - file7.txt - exists - exists (same) - none - same 2026/07/27 06:32:35 NOTICE: (29) : test run bisync with resync 2026/07/27 06:32:35 NOTICE: (30) : bisync resync 2026/07/27 06:32:35 DEBUG : Reset feature "Copy" 2026/07/27 06:32:35 DEBUG : Reset feature "Copy" 2026/07/27 06:32:35 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:32:36 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:32:36 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "modtime_write_test" to batch 2026/07/27 06:32:37 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:32:37 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:37 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:40 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:32:41 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:32:41 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "modtime_write_test" to batch 2026/07/27 06:32:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:32:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:42 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:32:45 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:32:45 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:32:45 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:32:45 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:45 INFO : Copying Path2 files to Path1 2026/07/27 06:32:45 INFO : - Path2 Resync is copying files to - Path1 2026/07/27 06:32:47 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/check_access/path1': Committing uploads - please wait... 2026/07/27 06:32:48 INFO : file2.txt: Copied (new) 2026/07/27 06:32:48 INFO : file4.txt: Copied (new) 2026/07/27 06:32:48 INFO : - Path1 Resync is copying files to - Path2 2026/07/27 06:32:49 INFO : file6.txt: src and dst identical but can't set mod time without deleting and re-uploading 2026/07/27 06:32:52 INFO : file3.txt: Copied (replaced existing) 2026/07/27 06:32:52 INFO : file5.txt: Copied (replaced existing) 2026/07/27 06:32:52 INFO : file1.txt: Copied (new) 2026/07/27 06:32:53 INFO : file6.txt: Copied (replaced existing) 2026/07/27 06:32:53 INFO : Resync updating listings 2026/07/27 06:32:53 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:32:53 INFO : Bisync successful 2026/07/27 06:32:53 NOTICE: (31) : copy-listings mixed-diffs 2026/07/27 06:32:53 NOTICE: (32) : test run normal bisync 2026/07/27 06:32:53 NOTICE: (33) : bisync 2026/07/27 06:32:53 DEBUG : Reset feature "Copy" 2026/07/27 06:32:53 DEBUG : Reset feature "Copy" 2026/07/27 06:32:54 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:32:54 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:32:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "modtime_write_test" to batch 2026/07/27 06:32:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:32:55 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:56 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:32:59 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:32:59 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:33:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "modtime_write_test" to batch 2026/07/27 06:33:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:33:00 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:01 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:33:04 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:33:04 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:33:04 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:33:04 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:33:04 INFO : Building Path1 and Path2 listings 2026/07/27 06:33:04 INFO : Path1 checking for diffs 2026/07/27 06:33:04 INFO : Path2 checking for diffs 2026/07/27 06:33:04 INFO : No changes found 2026/07/27 06:33:04 INFO : Updating listings 2026/07/27 06:33:04 INFO : Validating listings for Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" vs Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:33:04 INFO : Bisync successful 2026/07/27 06:33:04 NOTICE: (34) : test 4. confirm critical error on normal sync of empty path. 2026/07/27 06:33:04 NOTICE: (35) : purge-children TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/ 2026/07/27 06:33:04 DEBUG : file2.txt: Remove file 2026/07/27 06:33:05 DEBUG : file7.txt: Remove file 2026/07/27 06:33:05 DEBUG : RCLONE_TEST: Remove file 2026/07/27 06:33:06 DEBUG : file4.txt: Remove file 2026/07/27 06:33:06 DEBUG : file1.txt: Remove file 2026/07/27 06:33:07 DEBUG : file3.txt: Remove file 2026/07/27 06:33:07 DEBUG : file5.txt: Remove file 2026/07/27 06:33:08 DEBUG : file6.txt: Remove file 2026/07/27 06:33:09 NOTICE: (36) : bisync 2026/07/27 06:33:09 DEBUG : Reset feature "Copy" 2026/07/27 06:33:09 DEBUG : Reset feature "Copy" 2026/07/27 06:33:09 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:33:09 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:33:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Adding "modtime_write_test" to batch 2026/07/27 06:33:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Batch idle for 500ms so committing 2026/07/27 06:33:10 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:11 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:14 DEBUG : modtime_write_test: Uploading chunk 1/1 2026/07/27 06:33:14 DEBUG : modtime_write_test: Uploading chunk 2/1 2026/07/27 06:33:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Adding "modtime_write_test" to batch 2026/07/27 06:33:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Batch idle for 500ms so committing 2026/07/27 06:33:15 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:16 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committed sync batch length 1 starting with: modtime_write_test 2026/07/27 06:33:19 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:33:19 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/07/27 06:33:19 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/07/27 06:33:19 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/07/27 06:33:19 INFO : Synching Path1 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path1/" with Path2 "TestDropbox:rclone-test-yizuyal5kaga/062547copuvil8/resync/path2/" 2026/07/27 06:33:19 INFO : Building Path1 and Path2 listings 2026/07/27 06:33:19 INFO : Path1 checking for diffs 2026/07/27 06:33:19 INFO : Path2 checking for diffs 2026/07/27 06:33:19 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/062547copuvil8/workdir/TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_resync_path1..TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_resync_path2.path2.lst-new 2026/07/27 06:33:19 ERROR : Bisync critical error: empty current Path2 listing: /tmp/062547copuvil8/workdir/TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_resync_path1..TestDropbox_rclone-test-yizuyal5kaga_062547copuvil8_resync_path2.path2.lst-new 2026/07/27 06:33:19 ERROR : Bisync aborted. Must run --resync to recover. 2026/07/27 06:33:19 NOTICE: Bisync error: bisync aborted bisync_test.go:610: TEST resync PASSED 2026/07/27 06:33:20 DEBUG : Dropbox root 'rclone-test-yizuyal5kaga': Purge remote --- FAIL: TestBisyncRemoteRemote (454.25s) --- PASS: TestBisyncRemoteRemote/check_access (126.96s) --- FAIL: TestBisyncRemoteRemote/resolve (157.57s) --- FAIL: TestBisyncRemoteRemote/resync (168.64s) FAIL 2026/07/27 06:33:21 DEBUG : Dropbox root 'rclone-test-sosibob4hixu': Purge remote 2026/07/27 06:33:21 NOTICE: purge failed: path_lookup/not_found/ 2026/07/27 06:33:21 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8': Committing uploads - please wait... 2026/07/27 06:33:21 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path1': Committing uploads - please wait... 2026/07/27 06:33:21 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resolve/path2': Committing uploads - please wait... 2026/07/27 06:33:21 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path2': Committing uploads - please wait... 2026/07/27 06:33:21 INFO : Dropbox root 'rclone-test-yizuyal5kaga/062547copuvil8/resync/path1': Committing uploads - please wait... "./bisync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestBisyncRemoteRemote$/^(check_access|resolve|resync)$'" - Finished ERROR in 7m34.979150412s (try 3/5): exit status 1: Failed [TestBisyncRemoteRemote/resolve TestBisyncRemoteRemote/resync]