"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestBisyncRemoteRemote$/^(max_delete_path2_force|nomodtime|normalization|rclone_args|resolve|resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/07/22 04:52:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jeleguv2vuyo"
2025/07/22 04:52:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/22 04:52:03 DEBUG : Creating backend with remote "TestDrive:crypt/hm01210u3ist7l1aoqstu2qdn91d1e7ga1g9h69u1kv2uhume4d0"
2025/07/22 04:52:04 DEBUG : Creating backend with remote "/tmp/rclone2438962192"
=== RUN   TestBisyncRemoteRemote
2025/07/22 04:52:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe"
2025/07/22 04:52:04 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg"
2025/07/22 04:52:05 NOTICE: remote: TestCryptDrive:rclone-test-civitaf8qefe
=== RUN   TestBisyncRemoteRemote/max_delete_path2_force
2025/07/22 04:52:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be"
2025/07/22 04:52:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/22 04:52:05 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c"
2025/07/22 04:52:06 INFO  : path1: Making directory
2025/07/22 04:52:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1"
2025/07/22 04:52:09 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/gsn144sjl4tq5r0k5ucjve7jd1s4krg50fm78jo69dd22khnt7l0/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 04:52:11 INFO  : path2: Making directory
2025/07/22 04:52:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2"
2025/07/22 04:52:12 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/gsn144sjl4tq5r0k5ucjve7jd1s4krg50fm78jo69dd22khnt7l0/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 04:52:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/initial"
2025/07/22 04:52:14 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_max_delete_path2_force-padiwud1"
2025/07/22 04:52:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 04:52:14 INFO  : file2.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 04:52:14 INFO  : file1.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : Local file system at /tmp/045205be/initdir/test_max_delete_path2_force-padiwud1: Waiting for checks to finish
2025/07/22 04:52:14 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/07/22 04:52:14 DEBUG : Local file system at /tmp/045205be/initdir/test_max_delete_path2_force-padiwud1: Waiting for transfers to finish
2025/07/22 04:52:14 INFO  : file5.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/07/22 04:52:14 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 INFO  : file4.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/07/22 04:52:14 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/07/22 04:52:14 INFO  : file6.txt: Copied (new)
2025/07/22 04:52:14 INFO  : file7.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/07/22 04:52:14 INFO  : file8.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:52:14 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/07/22 04:52:14 INFO  : file3.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 04:52:14 DEBUG : file9.txt.cf9a93ac.partial: renamed to: file9.txt
2025/07/22 04:52:14 INFO  : file9.txt: Copied (new)
2025/07/22 04:52:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/modfiles"
2025/07/22 04:52:14 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_max_delete_path2_force-cipazih8"
2025/07/22 04:52:14 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:14 DEBUG : Local file system at /tmp/045205be/datadir/test_max_delete_path2_force-cipazih8: Waiting for checks to finish
2025/07/22 04:52:14 DEBUG : Local file system at /tmp/045205be/datadir/test_max_delete_path2_force-cipazih8: Waiting for transfers to finish
2025/07/22 04:52:14 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/07/22 04:52:14 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/07/22 04:52:14 INFO  : hold.txt: Copied (new)
2025/07/22 04:52:15 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_max_delete_path2_force-padiwud1
2025/07/22 04:52:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1': Waiting for checks to finish
2025/07/22 04:52:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1': Waiting for transfers to finish
2025/07/22 04:52:17 DEBUG : file1.txt: md5 = a7a55207c73c2a0aea8001f44a489bc3 OK
2025/07/22 04:52:17 INFO  : file1.txt: Copied (new)
2025/07/22 04:52:18 DEBUG : file3.txt: md5 = a0eea50eb67bf6a9689a3ec41b0ce990 OK
2025/07/22 04:52:18 INFO  : file3.txt: Copied (new)
2025/07/22 04:52:18 DEBUG : file2.txt: md5 = c6df862a2def3c1acda9194da5dd4b2a OK
2025/07/22 04:52:18 INFO  : file2.txt: Copied (new)
2025/07/22 04:52:18 DEBUG : file4.txt: md5 = e9ee2eda7a541e3ead59d4011d74cc06 OK
2025/07/22 04:52:18 INFO  : file4.txt: Copied (new)
2025/07/22 04:52:28 DEBUG : file7.txt: md5 = 6534cf6ae7753be09bb5ca9af9122adb OK
2025/07/22 04:52:28 INFO  : file7.txt: Copied (new)
2025/07/22 04:52:28 DEBUG : file8.txt: md5 = 6fbf3701611d17952ed6b6b7a645bf57 OK
2025/07/22 04:52:28 INFO  : file8.txt: Copied (new)
2025/07/22 04:52:29 DEBUG : file6.txt: md5 = 4973101c6d1043370e74c47e0e43eb58 OK
2025/07/22 04:52:29 INFO  : file6.txt: Copied (new)
2025/07/22 04:52:29 DEBUG : file5.txt: md5 = 73fc59b5f2659bb81fa96b35b0821288 OK
2025/07/22 04:52:29 INFO  : file5.txt: Copied (new)
2025/07/22 04:52:30 DEBUG : file9.txt: md5 = 0e1e85ca77360c2f58ead1a22a17f17e OK
2025/07/22 04:52:30 INFO  : file9.txt: Copied (new)
2025/07/22 04:52:30 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1'
2025/07/22 04:52:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/07/22 04:52:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2': Waiting for checks to finish
2025/07/22 04:52:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2': Waiting for transfers to finish
2025/07/22 04:52:33 DEBUG : file1.txt: md5 = b4a436c69294a550cab54c9a48494ca4 OK
2025/07/22 04:52:33 INFO  : file1.txt: Copied (new)
2025/07/22 04:52:33 DEBUG : file4.txt: md5 = 8053b886a0e92608ca6506383af3824c OK
2025/07/22 04:52:33 INFO  : file4.txt: Copied (new)
2025/07/22 04:52:33 DEBUG : file3.txt: md5 = cdb4dc7fd239d40a3940174dac78f907 OK
2025/07/22 04:52:33 INFO  : file3.txt: Copied (new)
2025/07/22 04:52:33 DEBUG : file2.txt: md5 = 1c95050280e83ffcec567cdad8802256 OK
2025/07/22 04:52:33 INFO  : file2.txt: Copied (new)
2025/07/22 04:52:34 DEBUG : file5.txt: md5 = d288434fdb9d7c1a37e675f70cd507c0 OK
2025/07/22 04:52:34 INFO  : file5.txt: Copied (new)
2025/07/22 04:52:34 DEBUG : file6.txt: md5 = 3cee938b404a6378fe24e266ba2be23b OK
2025/07/22 04:52:34 INFO  : file6.txt: Copied (new)
2025/07/22 04:52:35 DEBUG : file7.txt: md5 = 8cc24861b11494421baf7d6d1670ab80 OK
2025/07/22 04:52:35 INFO  : file7.txt: Copied (new)
2025/07/22 04:52:35 DEBUG : file8.txt: md5 = b0d3c2c0f63a95ddc062f8c2ce02bbc3 OK
2025/07/22 04:52:35 INFO  : file8.txt: Copied (new)
2025/07/22 04:52:35 DEBUG : file9.txt: md5 = 98d2d6322facfa069e59cd3c90495cc1 OK
2025/07/22 04:52:35 INFO  : file9.txt: Copied (new)
2025/07/22 04:52:35 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2'
2025/07/22 04:52:36 NOTICE: (01)  : test max-delete-path2-force
2025/07/22 04:52:36 NOTICE: (02)  : test initial bisync
2025/07/22 04:52:36 NOTICE: (03)  : bisync resync
2025/07/22 04:52:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:52:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:52:50 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/"
2025/07/22 04:52:50 INFO  : Copying Path2 files to Path1
2025/07/22 04:52:50 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:52:50 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:52:50 INFO  : Resync updating listings
2025/07/22 04:52:50 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/"
2025/07/22 04:52:50 INFO  : Bisync successful
2025/07/22 04:52:50 NOTICE: (04)  : test delete >50% of remote files
2025/07/22 04:52:50 NOTICE: (05)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/file1.txt
2025/07/22 04:52:51 INFO  : file1.txt: Deleted
2025/07/22 04:52:51 NOTICE: (06)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/file2.txt
2025/07/22 04:52:52 INFO  : file2.txt: Deleted
2025/07/22 04:52:52 NOTICE: (07)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/file3.txt
2025/07/22 04:52:52 INFO  : file3.txt: Deleted
2025/07/22 04:52:52 NOTICE: (08)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/file4.txt
2025/07/22 04:52:53 INFO  : file4.txt: Deleted
2025/07/22 04:52:53 NOTICE: (09)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/file5.txt
2025/07/22 04:52:54 INFO  : file5.txt: Deleted
2025/07/22 04:52:54 NOTICE: (10)  : test sync should fail due to too many path2 deletes
2025/07/22 04:52:54 NOTICE: (11)  : bisync
2025/07/22 04:52:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:52:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:52:59 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/"
2025/07/22 04:52:59 INFO  : Building Path1 and Path2 listings
2025/07/22 04:52:59 INFO  : Path1 checking for diffs
2025/07/22 04:52:59 INFO  : Path2 checking for diffs
2025/07/22 04:52:59 INFO  : - Path2    File was deleted          - file1.txt
2025/07/22 04:52:59 INFO  : - Path2    File was deleted          - file2.txt
2025/07/22 04:52:59 INFO  : - Path2    File was deleted          - file3.txt
2025/07/22 04:52:59 INFO  : - Path2    File was deleted          - file4.txt
2025/07/22 04:52:59 INFO  : - Path2    File was deleted          - file5.txt
2025/07/22 04:52:59 INFO  : Path2:    5 changes:    0 new,    0 modified,    5 deleted
2025/07/22 04:52:59 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/". Run with --force if desired.
2025/07/22 04:52:59 NOTICE: Bisync aborted. Please try again.
2025/07/22 04:52:59 NOTICE: Bisync error: too many deletes
2025/07/22 04:52:59 NOTICE: (12)  : copy-listings initial-fail
2025/07/22 04:52:59 NOTICE: (13)  : test apply force option. sync should run.
2025/07/22 04:52:59 NOTICE: (14)  : bisync force
2025/07/22 04:53:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:53:04 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:53:04 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/"
2025/07/22 04:53:04 INFO  : Building Path1 and Path2 listings
2025/07/22 04:53:04 INFO  : Path1 checking for diffs
2025/07/22 04:53:04 INFO  : Path2 checking for diffs
2025/07/22 04:53:04 INFO  : - Path2    File was deleted          - file1.txt
2025/07/22 04:53:04 INFO  : - Path2    File was deleted          - file2.txt
2025/07/22 04:53:04 INFO  : - Path2    File was deleted          - file3.txt
2025/07/22 04:53:04 INFO  : - Path2    File was deleted          - file4.txt
2025/07/22 04:53:04 INFO  : - Path2    File was deleted          - file5.txt
2025/07/22 04:53:04 INFO  : Path2:    5 changes:    0 new,    0 modified,    5 deleted
2025/07/22 04:53:04 INFO  : Applying changes
2025/07/22 04:53:04 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/file1.txt
2025/07/22 04:53:04 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/file2.txt
2025/07/22 04:53:04 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/file3.txt
2025/07/22 04:53:04 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/file4.txt
2025/07/22 04:53:04 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/file5.txt
2025/07/22 04:53:04 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:53:06 INFO  : file3.txt: Deleted
2025/07/22 04:53:06 INFO  : file5.txt: Deleted
2025/07/22 04:53:06 INFO  : file1.txt: Deleted
2025/07/22 04:53:06 INFO  : file2.txt: Deleted
2025/07/22 04:53:06 INFO  : file4.txt: Deleted
2025/07/22 04:53:06 INFO  : Updating listings
2025/07/22 04:53:06 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/max_delete_path2_force/path2/"
2025/07/22 04:53:06 INFO  : Bisync successful
    bisync_test.go:560: TEST max_delete_path2_force PASSED
=== RUN   TestBisyncRemoteRemote/nomodtime
2025/07/22 04:53:07 INFO  : path1: Making directory
2025/07/22 04:53:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1"
2025/07/22 04:53:07 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/l3ff807j0msirh9hir3cf3bqtc/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 04:53:09 INFO  : path2: Making directory
2025/07/22 04:53:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2"
2025/07/22 04:53:09 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/l3ff807j0msirh9hir3cf3bqtc/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 04:53:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_nomodtime/initial"
2025/07/22 04:53:11 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_nomodtime-fojuvux2"
2025/07/22 04:53:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 04:53:11 INFO  : file1.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : Local file system at /tmp/045205be/initdir/test_nomodtime-fojuvux2: Waiting for checks to finish
2025/07/22 04:53:11 DEBUG : Local file system at /tmp/045205be/initdir/test_nomodtime-fojuvux2: Waiting for transfers to finish
2025/07/22 04:53:11 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 04:53:11 INFO  : file2.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/07/22 04:53:11 INFO  : file3.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/07/22 04:53:11 INFO  : file4.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/07/22 04:53:11 INFO  : file5.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/07/22 04:53:11 INFO  : file6.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/07/22 04:53:11 INFO  : file7.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 04:53:11 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 04:53:11 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:53:11 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:53:11 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/07/22 04:53:11 INFO  : file8.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_nomodtime/modfiles"
2025/07/22 04:53:11 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_nomodtime-towalus8"
2025/07/22 04:53:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:11 DEBUG : Local file system at /tmp/045205be/datadir/test_nomodtime-towalus8: Waiting for checks to finish
2025/07/22 04:53:11 DEBUG : Local file system at /tmp/045205be/datadir/test_nomodtime-towalus8: Waiting for transfers to finish
2025/07/22 04:53:11 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:53:11 DEBUG : file11.txt.7d45770.partial: renamed to: file11.txt
2025/07/22 04:53:11 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:53:11 INFO  : file11.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file1.txt.7d45770.partial: renamed to: file1.txt
2025/07/22 04:53:11 INFO  : file1.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/07/22 04:53:11 DEBUG : file2.txt.21f2ec7f.partial: renamed to: file2.txt
2025/07/22 04:53:11 INFO  : file2.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:53:11 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/07/22 04:53:11 DEBUG : file10.txt.7d45770.partial: renamed to: file10.txt
2025/07/22 04:53:11 INFO  : file10.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:53:11 DEBUG : file6.txt.7d45770.partial: renamed to: file6.txt
2025/07/22 04:53:11 INFO  : file6.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file5L.txt.9ed658ed.partial: renamed to: file5L.txt
2025/07/22 04:53:11 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/07/22 04:53:11 INFO  : file5L.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file5R.txt.9ed658ed.partial: renamed to: file5R.txt
2025/07/22 04:53:11 INFO  : file5R.txt: Copied (new)
2025/07/22 04:53:11 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:53:11 DEBUG : file7.txt.7d45770.partial: renamed to: file7.txt
2025/07/22 04:53:11 INFO  : file7.txt: Copied (new)
2025/07/22 04:53:12 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_nomodtime-fojuvux2
2025/07/22 04:53:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1': Waiting for checks to finish
2025/07/22 04:53:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1': Waiting for transfers to finish
2025/07/22 04:53:25 DEBUG : file1.txt: md5 = e79baf4e6b82edecd749179c712a5719 OK
2025/07/22 04:53:25 INFO  : file1.txt: Copied (new)
2025/07/22 04:53:25 DEBUG : RCLONE_TEST: md5 = 41b4bcfca4d59e96f9bfc0c6c126236a OK
2025/07/22 04:53:25 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:53:25 DEBUG : file3.txt: md5 = 98040b473e1f12b783331159d1c53878 OK
2025/07/22 04:53:25 INFO  : file3.txt: Copied (new)
2025/07/22 04:53:25 DEBUG : file2.txt: md5 = e4ca0d817e48bc43e5e2ed348317b6b3 OK
2025/07/22 04:53:25 INFO  : file2.txt: Copied (new)
2025/07/22 04:53:26 DEBUG : file5.txt: md5 = bfe2ed9793622c47bb535068cdd429e7 OK
2025/07/22 04:53:26 INFO  : file5.txt: Copied (new)
2025/07/22 04:53:26 DEBUG : file4.txt: md5 = a7fa5421b7736653625d344e7e1db733 OK
2025/07/22 04:53:26 INFO  : file4.txt: Copied (new)
2025/07/22 04:53:26 DEBUG : file6.txt: md5 = 284df98a002d737048ed85633626627d OK
2025/07/22 04:53:26 INFO  : file6.txt: Copied (new)
2025/07/22 04:53:26 DEBUG : file7.txt: md5 = aee17c592853e2cd858b0dad40e5bd0f OK
2025/07/22 04:53:26 INFO  : file7.txt: Copied (new)
2025/07/22 04:53:28 DEBUG : file8.txt: md5 = 1ca1b2da9785748a0f71653e37adef19 OK
2025/07/22 04:53:28 INFO  : file8.txt: Copied (new)
2025/07/22 04:53:28 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1'
2025/07/22 04:53:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 04:53:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2': Waiting for checks to finish
2025/07/22 04:53:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2': Waiting for transfers to finish
2025/07/22 04:53:31 DEBUG : RCLONE_TEST: md5 = f7e8b00cbef3cb17c6a360ca2b04aa6d OK
2025/07/22 04:53:31 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:53:31 DEBUG : file1.txt: md5 = f50cdfd343d77299eb2a55eace2fcbce OK
2025/07/22 04:53:31 INFO  : file1.txt: Copied (new)
2025/07/22 04:53:31 DEBUG : file3.txt: md5 = c177da4fa272691b7e7772d12bada084 OK
2025/07/22 04:53:31 INFO  : file3.txt: Copied (new)
2025/07/22 04:53:31 DEBUG : file2.txt: md5 = bdd860f4e68dfdfa50b57d6194c24fb5 OK
2025/07/22 04:53:31 INFO  : file2.txt: Copied (new)
2025/07/22 04:53:32 DEBUG : file4.txt: md5 = 7a0f9f3759fd483c4a2e2029c12c37b6 OK
2025/07/22 04:53:32 INFO  : file4.txt: Copied (new)
2025/07/22 04:53:32 DEBUG : file5.txt: md5 = b4367a50d58a143942da053eb0829cf5 OK
2025/07/22 04:53:32 INFO  : file5.txt: Copied (new)
2025/07/22 04:53:32 DEBUG : file7.txt: md5 = 4f914f60ee106c3144bd1c91c2479217 OK
2025/07/22 04:53:32 INFO  : file7.txt: Copied (new)
2025/07/22 04:53:32 DEBUG : file6.txt: md5 = 3f061465a4c76a5a222dc8b22ea9f53a OK
2025/07/22 04:53:32 INFO  : file6.txt: Copied (new)
2025/07/22 04:53:33 DEBUG : file8.txt: md5 = 8aa3ee21f360a970624c59be28c40450 OK
2025/07/22 04:53:33 INFO  : file8.txt: Copied (new)
2025/07/22 04:53:33 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2'
2025/07/22 04:53:34 NOTICE: (01)  : test changes
2025/07/22 04:53:34 NOTICE: (02)  : test initial bisync
2025/07/22 04:53:34 NOTICE: (03)  : bisync resync nomodtime
2025/07/22 04:53:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 04:53:39 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/"
2025/07/22 04:53:39 INFO  : Copying Path2 files to Path1
2025/07/22 04:53:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:53:39 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 04:53:54 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:54:10 INFO  : Resync updating listings
2025/07/22 04:54:10 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/"
2025/07/22 04:54:10 INFO  : Bisync successful
2025/07/22 04:54:10 NOTICE: (04)  : test make modifications on both paths
2025/07/22 04:54:10 NOTICE: (05)  : test new on path2 - file10
2025/07/22 04:54:10 NOTICE: (06)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file10.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/
2025/07/22 04:54:11 INFO  : file10.txt: Copied (new)
2025/07/22 04:54:11 NOTICE: (07)  : test changed on path2 - file1
2025/07/22 04:54:11 NOTICE: (08)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/
2025/07/22 04:54:13 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:54:13 NOTICE: (09)  : test new on path1 - file11
2025/07/22 04:54:13 NOTICE: (10)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file11.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/
2025/07/22 04:54:15 INFO  : file11.txt: Copied (new)
2025/07/22 04:54:15 NOTICE: (11)  : test changed on path1 - file2
2025/07/22 04:54:15 NOTICE: (12)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file2.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/
2025/07/22 04:54:16 INFO  : file2.txt: Copied (replaced existing)
2025/07/22 04:54:16 NOTICE: (13)  : test deleted on path2 - file3
2025/07/22 04:54:16 NOTICE: (14)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file3.txt
2025/07/22 04:54:17 INFO  : file3.txt: Deleted
2025/07/22 04:54:17 NOTICE: (15)  : test deleted on path1 - file4
2025/07/22 04:54:17 NOTICE: (16)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file4.txt
2025/07/22 04:54:18 INFO  : file4.txt: Deleted
2025/07/22 04:54:18 NOTICE: (17)  : test deleted on both paths - file8
2025/07/22 04:54:18 NOTICE: (18)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file8.txt
2025/07/22 04:54:19 INFO  : file8.txt: Deleted
2025/07/22 04:54:19 NOTICE: (19)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file8.txt
2025/07/22 04:54:19 INFO  : file8.txt: Deleted
2025/07/22 04:54:19 NOTICE: (20)  : test changed on both paths - file5 (file5R, file5L)
2025/07/22 04:54:19 NOTICE: (21)  : copy-as /tmp/045205be/datadir/test_nomodtime-towalus8/file5R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/ file5.txt
2025/07/22 04:54:21 INFO  : file5R.txt: Copied (replaced existing) to: file5.txt
2025/07/22 04:54:21 NOTICE: (22)  : copy-as /tmp/045205be/datadir/test_nomodtime-towalus8/file5L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/ file5.txt
2025/07/22 04:54:22 INFO  : file5L.txt: Copied (replaced existing) to: file5.txt
2025/07/22 04:54:22 NOTICE: (23)  : test changed on path2 and deleted on path1 - file6
2025/07/22 04:54:22 NOTICE: (24)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file6.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/
2025/07/22 04:54:23 INFO  : file6.txt: Copied (replaced existing)
2025/07/22 04:54:23 NOTICE: (25)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file6.txt
2025/07/22 04:54:24 INFO  : file6.txt: Deleted
2025/07/22 04:54:24 NOTICE: (26)  : test changed on path1 and deleted on path2 - file7
2025/07/22 04:54:24 NOTICE: (27)  : copy-file /tmp/045205be/datadir/test_nomodtime-towalus8/file7.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/
2025/07/22 04:54:26 INFO  : file7.txt: Copied (replaced existing)
2025/07/22 04:54:26 NOTICE: (28)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file7.txt
2025/07/22 04:54:26 INFO  : file7.txt: Deleted
2025/07/22 04:54:26 NOTICE: (29)  : test bisync run
2025/07/22 04:54:26 NOTICE: (30)  : bisync nomodtime
2025/07/22 04:54:31 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 04:54:31 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/"
2025/07/22 04:54:31 INFO  : Building Path1 and Path2 listings
2025/07/22 04:54:32 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 04:54:44 INFO  : Path1 checking for diffs
2025/07/22 04:54:44 INFO  : - Path1    File changed: size (larger), hash - file2.txt
2025/07/22 04:54:44 INFO  : - Path1    File was deleted          - file4.txt
2025/07/22 04:54:44 INFO  : - Path1    File changed: size (larger), hash - file5.txt
2025/07/22 04:54:44 INFO  : - Path1    File was deleted          - file6.txt
2025/07/22 04:54:44 INFO  : - Path1    File changed: size (larger), hash - file7.txt
2025/07/22 04:54:44 INFO  : - Path1    File was deleted          - file8.txt
2025/07/22 04:54:44 INFO  : - Path1    File is new               - file11.txt
2025/07/22 04:54:44 INFO  : Path1:    7 changes:    1 new,    3 modified,    3 deleted
2025/07/22 04:54:44 INFO  : (Modified:    3 larger,    0 smaller,    3 hash differs)
2025/07/22 04:54:44 INFO  : Path2 checking for diffs
2025/07/22 04:54:44 INFO  : - Path2    File changed: size (larger), hash - file1.txt
2025/07/22 04:54:44 INFO  : - Path2    File was deleted          - file3.txt
2025/07/22 04:54:44 INFO  : - Path2    File changed: size (larger), hash - file5.txt
2025/07/22 04:54:44 INFO  : - Path2    File changed: size (larger), hash - file6.txt
2025/07/22 04:54:44 INFO  : - Path2    File was deleted          - file7.txt
2025/07/22 04:54:44 INFO  : - Path2    File was deleted          - file8.txt
2025/07/22 04:54:44 INFO  : - Path2    File is new               - file10.txt
2025/07/22 04:54:44 INFO  : Path2:    7 changes:    1 new,    3 modified,    3 deleted
2025/07/22 04:54:44 INFO  : (Modified:    3 larger,    0 smaller,    3 hash differs)
2025/07/22 04:54:44 INFO  : Applying changes
2025/07/22 04:54:44 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file11.txt
2025/07/22 04:54:44 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file2.txt
2025/07/22 04:54:44 INFO  : - Path2    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file4.txt
2025/07/22 04:54:44 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/07/22 04:54:44 NOTICE: - Path1    Renaming Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file5.txt.conflict1
2025/07/22 04:54:44 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict1
2025/07/22 04:54:44 NOTICE: - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file5.txt.conflict1
2025/07/22 04:54:44 NOTICE: - Path2    Renaming Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file5.txt.conflict2
2025/07/22 04:54:45 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict2
2025/07/22 04:54:45 NOTICE: - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file5.txt.conflict2
2025/07/22 04:54:45 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file6.txt
2025/07/22 04:54:45 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/file7.txt
2025/07/22 04:54:45 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file1.txt
2025/07/22 04:54:45 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file10.txt
2025/07/22 04:54:45 INFO  : - Path1    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/file3.txt
2025/07/22 04:54:45 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:54:48 INFO  : file10.txt: Copied (server-side copy)
2025/07/22 04:54:50 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:54:50 INFO  : file5.txt.conflict2: Copied (server-side copy)
2025/07/22 04:54:51 INFO  : file6.txt: Copied (server-side copy)
2025/07/22 04:54:52 INFO  : file3.txt: Deleted
2025/07/22 04:54:52 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 04:54:54 INFO  : file11.txt: Copied (server-side copy)
2025/07/22 04:54:55 INFO  : file5.txt.conflict1: Copied (server-side copy)
2025/07/22 04:54:57 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 04:54:57 INFO  : file7.txt: Copied (server-side copy)
2025/07/22 04:54:58 INFO  : file4.txt: Deleted
2025/07/22 04:54:58 INFO  : Updating listings
2025/07/22 04:54:58 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/nomodtime/path2/"
2025/07/22 04:54:58 INFO  : Bisync successful
    bisync_test.go:560: TEST nomodtime PASSED
=== RUN   TestBisyncRemoteRemote/normalization
2025/07/22 04:54:59 INFO  : path1: Making directory
2025/07/22 04:54:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1"
2025/07/22 04:54:59 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/md4aj6t306clou4ql6adn1mj5s/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 04:55:01 INFO  : path2: Making directory
2025/07/22 04:55:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2"
2025/07/22 04:55:01 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/md4aj6t306clou4ql6adn1mj5s/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 04:55:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/initial"
2025/07/22 04:55:03 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_normalization-pocumes2"
2025/07/22 04:55:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2025/07/22 04:55:03 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2025/07/22 04:55:03 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2025/07/22 04:55:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2025/07/22 04:55:03 INFO  : file1.txt: Copied (new)
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:55:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 04:55:03 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 04:55:03 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.5028a97.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2025/07/22 04:55:03 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2025/07/22 04:55:03 DEBUG : Local file system at /tmp/045205be/initdir/test_normalization-pocumes2: Waiting for checks to finish
2025/07/22 04:55:03 DEBUG : Local file system at /tmp/045205be/initdir/test_normalization-pocumes2: Waiting for transfers to finish
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.480f4fd7.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2025/07/22 04:55:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2025/07/22 04:55:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.480f4fd7.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2025/07/22 04:55:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2025/07/22 04:55:03 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2025/07/22 04:55:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/modfiles"
2025/07/22 04:55:03 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_normalization-juvesey1"
2025/07/22 04:55:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:03 DEBUG : Local file system at /tmp/045205be/datadir/test_normalization-juvesey1: Waiting for checks to finish
2025/07/22 04:55:03 DEBUG : Local file system at /tmp/045205be/datadir/test_normalization-juvesey1: Waiting for transfers to finish
2025/07/22 04:55:03 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:55:03 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2025/07/22 04:55:03 DEBUG : 測試_filtersfile.txt.e8d47346.partial: renamed to: 測試_filtersfile.txt
2025/07/22 04:55:03 INFO  : 測試_filtersfile.txt: Copied (new)
2025/07/22 04:55:03 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/07/22 04:55:03 INFO  : file1.txt: Copied (new)
2025/07/22 04:55:04 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_normalization-pocumes2
2025/07/22 04:55:04 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:55:04 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:04 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2025/07/22 04:55:06 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2025/07/22 04:55:06 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:06 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:06 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2025/07/22 04:55:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1': Waiting for checks to finish
2025/07/22 04:55:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1': Waiting for transfers to finish
2025/07/22 04:55:07 DEBUG : RCLONE_TEST: md5 = dca5633d99ce50afade8555873399a89 OK
2025/07/22 04:55:07 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:55:07 DEBUG : file1.txt: md5 = 479da98f99c52c201b960a3b3fc93416 OK
2025/07/22 04:55:07 INFO  : file1.txt: Copied (new)
2025/07/22 04:55:08 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = a61a32ca9e85e8d9014711c48c9afac6 OK
2025/07/22 04:55:08 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2025/07/22 04:55:08 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = c61e0befabc3d10fa709c6628d623eda OK
2025/07/22 04:55:08 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2025/07/22 04:55:08 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = a1ec54253d8bcc991cddbb876d525e4f OK
2025/07/22 04:55:08 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2025/07/22 04:55:08 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1'
2025/07/22 04:55:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:55:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:09 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2025/07/22 04:55:11 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2025/07/22 04:55:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2025/07/22 04:55:11 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2025/07/22 04:55:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': Waiting for checks to finish
2025/07/22 04:55:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': Waiting for transfers to finish
2025/07/22 04:55:12 DEBUG : file1.txt: md5 = e289b63793999235cacae31ebeee449c OK
2025/07/22 04:55:12 INFO  : file1.txt: Copied (new)
2025/07/22 04:55:12 DEBUG : RCLONE_TEST: md5 = ba833455b3a4c19ab1130429bf06c3a4 OK
2025/07/22 04:55:12 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:55:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 4a6aa2400ec9f55df9c4671faeb5ac2b OK
2025/07/22 04:55:12 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2025/07/22 04:55:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = e72b12c7b065540a89497b1953f03737 OK
2025/07/22 04:55:12 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2025/07/22 04:55:13 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = e013ddd915fed7fe5fd13182bfad304f OK
2025/07/22 04:55:13 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2025/07/22 04:55:13 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2'
2025/07/22 04:55:13 NOTICE: (01)  : test normalization
2025/07/22 04:55:13 NOTICE: (02)  : touch-copy 2001-01-02 /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:55:15 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:55:15 NOTICE: (03)  : test initial bisync
2025/07/22 04:55:15 NOTICE: (04)  : bisync resync
2025/07/22 04:55:22 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:55:26 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:55:28 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2025/07/22 04:55:29 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2025/07/22 04:55:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:55:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:55:29 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:55:29 INFO  : Copying Path2 files to Path1
2025/07/22 04:55:29 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:55:29 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:55:31 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:55:31 INFO  : Resync updating listings
2025/07/22 04:55:31 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:55:31 INFO  : Bisync successful
2025/07/22 04:55:31 NOTICE: (05)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:55:37 INFO  : file1.txt: Copied (new) to: 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:55:37 NOTICE: (06)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/folder éééö.txt
2025/07/22 04:55:42 INFO  : file1.txt: Copied (new) to: éééö.txt
2025/07/22 04:55:42 NOTICE: (07)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/folder HeLlO,wOrLd!.txt
2025/07/22 04:55:44 INFO  : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt
2025/07/22 04:55:44 NOTICE: (08)  : touch-copy 2001-01-03 /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:55:46 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:55:46 NOTICE: (09)  : copy-as-NFD /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/folder éééö.txt
2025/07/22 04:55:51 INFO  : file1.txt: Copied (new) to: éééö.txt
2025/07/22 04:55:51 NOTICE: (10)  : copy-as-NFD /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/folder hello,WORLD!.txt
2025/07/22 04:55:52 INFO  : file1.txt: Copied (new) to: hello,WORLD!.txt
2025/07/22 04:55:52 NOTICE: (11)  : test bisync run with fix-case
2025/07/22 04:55:52 NOTICE: (12)  : bisync fix-case
2025/07/22 04:56:00 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:56:04 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:56:06 INFO  : folder: Set directory modification time (using SetModTime)
2025/07/22 04:56:06 INFO  : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:56:07 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2025/07/22 04:56:08 INFO  : folder: Set directory modification time (using SetModTime)
2025/07/22 04:56:08 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2025/07/22 04:56:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:56:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:56:08 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:56:08 INFO  : Building Path1 and Path2 listings
2025/07/22 04:56:09 INFO  : Path1 checking for diffs
2025/07/22 04:56:09 INFO  : - Path1    File is new               - folder/HeLlO,wOrLd!.txt
2025/07/22 04:56:09 INFO  : - Path1    File is new               - folder/éééö.txt
2025/07/22 04:56:09 INFO  : - Path1    File is new               - "測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:56:09 INFO  : Path1:    3 changes:    3 new,    0 modified,    0 deleted
2025/07/22 04:56:09 INFO  : Path2 checking for diffs
2025/07/22 04:56:09 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/07/22 04:56:09 INFO  : - Path2    File is new               - folder/éééö.txt
2025/07/22 04:56:09 INFO  : - Path2    File is new               - folder/hello,WORLD!.txt
2025/07/22 04:56:09 INFO  : Path2:    3 changes:    2 new,    1 modified,    0 deleted
2025/07/22 04:56:09 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/07/22 04:56:09 INFO  : Applying changes
2025/07/22 04:56:09 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 04:56:09 INFO  : Checking potential conflicts...
2025/07/22 04:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': 0 differences found
2025/07/22 04:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': 2 matching files
2025/07/22 04:56:11 INFO  : Finished checking the potential conflicts. %!s(<nil>)
2025/07/22 04:56:11 NOTICE: - WARNING  New or changed in both paths       - folder/HeLlO,wOrLd!.txt
2025/07/22 04:56:11 INFO  : folder/hello,WORLD!.txt: Files are equal but will copy anyway to fix case to folder/HeLlO,wOrLd!.txt
2025/07/22 04:56:11 NOTICE: - WARNING  New or changed in both paths       - folder/éééö.txt
2025/07/22 04:56:11 INFO  : folder/éééö.txt: Files are equal but will copy anyway to fix case to folder/éééö.txt
2025/07/22 04:56:11 INFO  : - Path1    Queue copy to Path2       - "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:56:11 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/file1.txt
2025/07/22 04:56:11 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:56:13 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:56:13 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 04:56:15 INFO  : folder/hello,WORLD!.txt: Moved (server-side) to: folder/HeLlO,wOrLd!.txt
2025/07/22 04:56:15 INFO  : folder/hello,WORLD!.txt: Fixed case by renaming to: folder/HeLlO,wOrLd!.txt
2025/07/22 04:56:15 INFO  : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy)
2025/07/22 04:56:15 INFO  : folder/éééö.txt: Moved (server-side) to: folder/éééö.txt-rclone-move-decukos0
2025/07/22 04:56:15 INFO  : folder/éééö.txt-rclone-move-decukos0: Moved (server-side) to: folder/éééö.txt
2025/07/22 04:56:15 INFO  : folder/éééö.txt: Moved (server-side) to: folder/éééö.txt
2025/07/22 04:56:15 INFO  : folder/éééö.txt: Fixed case by renaming to: folder/éééö.txt
2025/07/22 04:56:16 INFO  : folder/HeLlO,wOrLd!.txt: Copied (server-side copy)
2025/07/22 04:56:17 INFO  : folder/éééö.txt: Copied (server-side copy)
2025/07/22 04:56:17 INFO  : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using DirSetModTime)
2025/07/22 04:56:17 INFO  : folder: Set directory modification time (using SetModTime)
2025/07/22 04:56:17 INFO  : Updating listings
2025/07/22 04:56:17 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:56:17 INFO  : Bisync successful
2025/07/22 04:56:17 NOTICE: (13)  : purge-children TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/
2025/07/22 04:56:20 NOTICE: (14)  : purge-children TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:56:22 NOTICE: (15)  : touch-copy 2001-01-02 /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:56:24 INFO  : file1.txt: Copied (new)
2025/07/22 04:56:24 NOTICE: (16)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/ file2.txt
2025/07/22 04:56:25 INFO  : file1.txt: Copied (new) to: file2.txt
2025/07/22 04:56:25 NOTICE: (17)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/ file3.txt
2025/07/22 04:56:27 INFO  : file1.txt: Copied (new) to: file3.txt
2025/07/22 04:56:27 NOTICE: (18)  : bisync resync
2025/07/22 04:56:34 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:56:38 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:56:40 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:56:40 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:56:40 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:56:40 INFO  : Copying Path2 files to Path1
2025/07/22 04:56:40 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:56:41 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 04:56:41 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:56:41 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:56:42 INFO  : file3.txt: Copied (server-side copy)
2025/07/22 04:56:42 INFO  : Resync updating listings
2025/07/22 04:56:42 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:56:42 INFO  : Bisync successful
2025/07/22 04:56:42 NOTICE: (19)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:56:48 INFO  : file1.txt: Copied (new) to: 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:56:48 NOTICE: (20)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder éééö.txt
2025/07/22 04:56:53 INFO  : file1.txt: Copied (new) to: éééö.txt
2025/07/22 04:56:53 NOTICE: (21)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder HeLlO,wOrLd!.txt
2025/07/22 04:56:55 INFO  : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt
2025/07/22 04:56:55 NOTICE: (22)  : touch-copy 2001-01-03 /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:56:56 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:56:56 NOTICE: (23)  : copy-as-NFD /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/newfolder éééö.txt
2025/07/22 04:57:01 INFO  : file1.txt: Copied (new) to: éééö.txt
2025/07/22 04:57:01 NOTICE: (24)  : copy-as-NFD /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/newfolder hello,WORLD!.txt
2025/07/22 04:57:03 INFO  : file1.txt: Copied (new) to: hello,WORLD!.txt
2025/07/22 04:57:03 NOTICE: (25)  : test bisync run with normalization
2025/07/22 04:57:03 NOTICE: (26)  : bisync norm force
2025/07/22 04:57:12 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:57:16 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:57:18 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:57:18 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:57:19 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:57:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:57:19 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:57:19 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:57:19 INFO  : Building Path1 and Path2 listings
2025/07/22 04:57:20 INFO  : Path1 checking for diffs
2025/07/22 04:57:20 INFO  : - Path1    File is new               - newfolder/HeLlO,wOrLd!.txt
2025/07/22 04:57:20 INFO  : - Path1    File is new               - newfolder/éééö.txt
2025/07/22 04:57:20 INFO  : - Path1    File is new               - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:57:20 INFO  : Path1:    3 changes:    3 new,    0 modified,    0 deleted
2025/07/22 04:57:20 INFO  : Path2 checking for diffs
2025/07/22 04:57:20 INFO  : - Path2    File changed: time (newer) - file1.txt
2025/07/22 04:57:20 INFO  : - Path2    File is new               - newfolder/éééö.txt
2025/07/22 04:57:20 INFO  : - Path2    File is new               - newfolder/hello,WORLD!.txt
2025/07/22 04:57:20 INFO  : Path2:    3 changes:    2 new,    1 modified,    0 deleted
2025/07/22 04:57:20 INFO  : (Modified:    1 newer,    0 older)
2025/07/22 04:57:20 INFO  : Applying changes
2025/07/22 04:57:20 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 04:57:20 INFO  : Checking potential conflicts...
2025/07/22 04:57:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': 0 differences found
2025/07/22 04:57:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2': 2 matching files
2025/07/22 04:57:22 INFO  : Finished checking the potential conflicts. %!s(<nil>)
2025/07/22 04:57:22 NOTICE: - WARNING  New or changed in both paths       - newfolder/HeLlO,wOrLd!.txt
2025/07/22 04:57:22 INFO  : newfolder/HeLlO,wOrLd!.txt: Files are equal but will copy anyway to update modtime (will not rename)
2025/07/22 04:57:22 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder/HeLlO,wOrLd!.txt
2025/07/22 04:57:22 NOTICE: - WARNING  New or changed in both paths       - newfolder/éééö.txt
2025/07/22 04:57:22 INFO  : newfolder/éééö.txt: Files are equal but will copy anyway to update modtime (will not rename)
2025/07/22 04:57:22 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder/éééö.txt
2025/07/22 04:57:22 INFO  : - Path1    Queue copy to Path2       - "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:57:22 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/file1.txt
2025/07/22 04:57:22 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:57:23 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:57:24 INFO  : newfolder/éééö.txt: Copied (server-side copy) to: newfolder/éééö.txt
2025/07/22 04:57:24 INFO  : newfolder/hello,WORLD!.txt: Copied (server-side copy) to: newfolder/HeLlO,wOrLd!.txt
2025/07/22 04:57:24 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:57:24 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 04:57:26 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy)
2025/07/22 04:57:27 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using DirSetModTime)
2025/07/22 04:57:27 INFO  : Updating listings
2025/07/22 04:57:27 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:57:27 INFO  : Bisync successful
2025/07/22 04:57:27 NOTICE: (27)  : test resync
2025/07/22 04:57:27 NOTICE: (28)  : bisync resync norm
2025/07/22 04:57:34 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:57:38 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:57:40 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:57:40 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:57:41 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:57:42 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:57:42 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:57:42 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:57:42 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:57:42 INFO  : Copying Path2 files to Path1
2025/07/22 04:57:42 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:57:42 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:57:43 INFO  : Resync updating listings
2025/07/22 04:57:44 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 04:57:45 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 04:57:47 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:57:47 INFO  : Bisync successful
2025/07/22 04:57:47 NOTICE: (29)  : test changed on one path
2025/07/22 04:57:47 NOTICE: (30)  : touch-copy 2001-01-05 /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/
2025/07/22 04:57:48 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:57:48 NOTICE: (31)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:57:50 INFO  : file1.txt: Copied (replaced existing) to: 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt
2025/07/22 04:57:50 NOTICE: (32)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder éééö.txt
2025/07/22 04:57:51 INFO  : file1.txt: Copied (replaced existing) to: éééö.txt
2025/07/22 04:57:51 NOTICE: (33)  : copy-as-NFC /tmp/045205be/datadir/test_normalization-juvesey1/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/newfolder HeLlO,wOrLd!.txt
2025/07/22 04:57:53 INFO  : file1.txt: Copied (replaced existing) to: HeLlO,wOrLd!.txt
2025/07/22 04:57:53 NOTICE: (34)  : bisync norm
2025/07/22 04:58:00 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:58:04 INFO  : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new)
2025/07/22 04:58:06 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:58:06 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:58:07 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:58:08 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:58:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:58:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:58:08 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:58:08 INFO  : Building Path1 and Path2 listings
2025/07/22 04:58:08 INFO  : Path1 checking for diffs
2025/07/22 04:58:08 INFO  : - Path1    File changed: time (newer) - newfolder/HeLlO,wOrLd!.txt
2025/07/22 04:58:08 INFO  : - Path1    File changed: time (newer) - newfolder/éééö.txt
2025/07/22 04:58:08 INFO  : - Path1    File changed: time (newer) - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:58:08 INFO  : Path1:    3 changes:    0 new,    3 modified,    0 deleted
2025/07/22 04:58:08 INFO  : (Modified:    3 newer,    0 older)
2025/07/22 04:58:08 INFO  : Path2 checking for diffs
2025/07/22 04:58:08 INFO  : - Path2    File changed: time (newer) - file1.txt
2025/07/22 04:58:08 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 04:58:08 INFO  : (Modified:    1 newer,    0 older)
2025/07/22 04:58:08 INFO  : Applying changes
2025/07/22 04:58:08 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/newfolder/hello,WORLD!.txt
2025/07/22 04:58:08 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/newfolder/éééö.txt
2025/07/22 04:58:08 INFO  : - Path1    Queue copy to Path2       - "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt"
2025/07/22 04:58:08 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/file1.txt
2025/07/22 04:58:08 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:58:10 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:58:10 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 04:58:12 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy)
2025/07/22 04:58:12 INFO  : newfolder/HeLlO,wOrLd!.txt: Copied (server-side copy) to: newfolder/hello,WORLD!.txt
2025/07/22 04:58:12 INFO  : newfolder/éééö.txt: Copied (server-side copy) to: newfolder/éééö.txt
2025/07/22 04:58:13 INFO  : newfolder: Set directory modification time (using SetModTime)
2025/07/22 04:58:13 INFO  : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Set directory modification time (using SetModTime)
2025/07/22 04:58:13 INFO  : Updating listings
2025/07/22 04:58:13 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/normalization/path2/"
2025/07/22 04:58:13 INFO  : Bisync successful
    bisync_test.go:560: TEST normalization PASSED
=== RUN   TestBisyncRemoteRemote/rclone_args
2025/07/22 04:58:14 INFO  : path1: Making directory
2025/07/22 04:58:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1"
2025/07/22 04:58:14 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/jqafno62pen2hubjbad7432124/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 04:58:16 INFO  : path2: Making directory
2025/07/22 04:58:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2"
2025/07/22 04:58:16 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/jqafno62pen2hubjbad7432124/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 04:58:18 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/initial"
2025/07/22 04:58:18 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_rclone_args-jitexoq5"
2025/07/22 04:58:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : subdir: Making directory with metadata
2025/07/22 04:58:18 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 04:58:18 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/07/22 04:58:18 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : Local file system at /tmp/045205be/initdir/test_rclone_args-jitexoq5: Waiting for checks to finish
2025/07/22 04:58:18 DEBUG : Local file system at /tmp/045205be/initdir/test_rclone_args-jitexoq5: Waiting for transfers to finish
2025/07/22 04:58:18 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:58:18 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 04:58:18 INFO  : file2.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:58:18 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:58:18 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/07/22 04:58:18 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 04:58:18 DEBUG : file1.txt.dae86b42.partial: renamed to: file1.txt
2025/07/22 04:58:18 INFO  : file1.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/07/22 04:58:18 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : subdir/file21.txt.5028a97.partial: renamed to: subdir/file21.txt
2025/07/22 04:58:18 INFO  : subdir/file21.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 04:58:18 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:58:18 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:58:18 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/modfiles"
2025/07/22 04:58:18 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_rclone_args-xeqaxir7"
2025/07/22 04:58:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : file20.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : file21.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:18 DEBUG : Local file system at /tmp/045205be/datadir/test_rclone_args-xeqaxir7: Waiting for checks to finish
2025/07/22 04:58:18 DEBUG : Local file system at /tmp/045205be/datadir/test_rclone_args-xeqaxir7: Waiting for transfers to finish
2025/07/22 04:58:18 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:58:18 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 04:58:18 DEBUG : file21.txt.b4075ff1.partial: renamed to: file21.txt
2025/07/22 04:58:18 DEBUG : file20.txt.b4075ff1.partial: renamed to: file20.txt
2025/07/22 04:58:18 INFO  : file21.txt: Copied (new)
2025/07/22 04:58:18 INFO  : file20.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:58:18 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 04:58:18 DEBUG : file1.txt.6bedbe24.partial: renamed to: file1.txt
2025/07/22 04:58:18 INFO  : file1.txt: Copied (new)
2025/07/22 04:58:18 DEBUG : file2.txt.6bedbe24.partial: renamed to: file2.txt
2025/07/22 04:58:18 INFO  : file2.txt: Copied (new)
2025/07/22 04:58:19 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_rclone_args-jitexoq5
2025/07/22 04:58:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:58:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:19 DEBUG : subdir: Making directory with metadata
2025/07/22 04:58:22 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 04:58:22 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:22 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1': Waiting for checks to finish
2025/07/22 04:58:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1': Waiting for transfers to finish
2025/07/22 04:58:22 DEBUG : RCLONE_TEST: md5 = 10bb03f5b10ced2f9351ce5b21880c37 OK
2025/07/22 04:58:22 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:58:22 DEBUG : file2.txt: md5 = 51f8335c1d2ae9008dea12583a5daf3e OK
2025/07/22 04:58:22 INFO  : file2.txt: Copied (new)
2025/07/22 04:58:22 DEBUG : file1.txt: md5 = 03c783acfe80d83a01eb074a67742014 OK
2025/07/22 04:58:22 INFO  : file1.txt: Copied (new)
2025/07/22 04:58:23 DEBUG : subdir/file20.txt: md5 = 39bc61a99843ac1dd00ba769bf206f65 OK
2025/07/22 04:58:23 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 04:58:24 DEBUG : subdir/file21.txt: md5 = 06dd7cea0a15d355787b9503a461b52e OK
2025/07/22 04:58:24 INFO  : subdir/file21.txt: Copied (new)
2025/07/22 04:58:24 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1'
2025/07/22 04:58:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 04:58:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:25 DEBUG : subdir: Making directory with metadata
2025/07/22 04:58:26 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 04:58:26 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:26 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/07/22 04:58:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2': Waiting for checks to finish
2025/07/22 04:58:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2': Waiting for transfers to finish
2025/07/22 04:58:27 DEBUG : file1.txt: md5 = 9901133fc5d8e4c77d6573e57cbb9d44 OK
2025/07/22 04:58:27 INFO  : file1.txt: Copied (new)
2025/07/22 04:58:27 DEBUG : RCLONE_TEST: md5 = 7c18d97cf4cf934a0b47272d3171492d OK
2025/07/22 04:58:27 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 04:58:27 DEBUG : file2.txt: md5 = f99b1b79d178ef14848674823c3467d7 OK
2025/07/22 04:58:27 INFO  : file2.txt: Copied (new)
2025/07/22 04:58:27 DEBUG : subdir/file20.txt: md5 = 085547cb9ffdd5762392a3121d3ec8c4 OK
2025/07/22 04:58:27 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 04:58:28 DEBUG : subdir/file21.txt: md5 = 1a0a4537d5d760445df50ea9faf67a2a OK
2025/07/22 04:58:28 INFO  : subdir/file21.txt: Copied (new)
2025/07/22 04:58:28 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2'
2025/07/22 04:58:29 NOTICE: (01)  : test rclone-args
2025/07/22 04:58:29 NOTICE: (02)  : touch-glob 2001-01-02 /tmp/045205be/datadir/test_rclone_args-xeqaxir7/ *
2025/07/22 04:58:29 NOTICE: (03)  : test initial bisync
2025/07/22 04:58:29 NOTICE: (04)  : bisync resync checksum
2025/07/22 04:58:34 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:58:35 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:58:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 04:58:35 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:58:35 INFO  : Copying Path2 files to Path1
2025/07/22 04:58:35 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:58:35 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 04:58:45 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:58:54 INFO  : Resync updating listings
2025/07/22 04:58:54 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:58:54 INFO  : Bisync successful
2025/07/22 04:58:54 NOTICE: (05)  : test place newer files on both paths
2025/07/22 04:58:54 NOTICE: (06)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/
2025/07/22 04:58:56 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:58:56 NOTICE: (07)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file2.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/
2025/07/22 04:58:57 INFO  : file2.txt: Copied (replaced existing)
2025/07/22 04:58:57 NOTICE: (08)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file20.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir
2025/07/22 04:59:00 INFO  : file20.txt: Copied (replaced existing)
2025/07/22 04:59:00 NOTICE: (09)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file21.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir
2025/07/22 04:59:03 INFO  : file21.txt: Copied (replaced existing)
2025/07/22 04:59:03 NOTICE: (10)  : touch-glob 2001-01-02 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/ file1.txt
2025/07/22 04:59:04 NOTICE: (11)  : touch-glob 2001-01-02 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file2.txt
2025/07/22 04:59:05 NOTICE: (12)  : touch-glob 2001-01-02 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/ file20.txt
2025/07/22 04:59:06 NOTICE: (13)  : touch-glob 2001-01-02 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir/ file21.txt
2025/07/22 04:59:07 NOTICE: (14)  : test run bisync with custom options
2025/07/22 04:59:07 NOTICE: (15)  : bisync checksum
2025/07/22 04:59:12 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:13 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:13 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 04:59:13 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:13 INFO  : Building Path1 and Path2 listings
2025/07/22 04:59:14 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 04:59:23 INFO  : Path1 checking for diffs
2025/07/22 04:59:23 INFO  : - Path1    File changed: hash - file1.txt
2025/07/22 04:59:23 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 04:59:23 INFO  : (Modified:    1 hash differs)
2025/07/22 04:59:23 INFO  : Path2 checking for diffs
2025/07/22 04:59:23 INFO  : - Path2    File changed: size (larger), hash - file2.txt
2025/07/22 04:59:23 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 04:59:23 INFO  : (Modified:    1 larger,    0 smaller,    1 hash differs)
2025/07/22 04:59:23 INFO  : Applying changes
2025/07/22 04:59:23 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/file1.txt
2025/07/22 04:59:23 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/file2.txt
2025/07/22 04:59:23 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:59:26 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 04:59:26 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 04:59:29 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:59:29 INFO  : Updating listings
2025/07/22 04:59:29 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:29 INFO  : Bisync successful
2025/07/22 04:59:29 NOTICE: (16)  : touch-glob 2007-07-23 /tmp/045205be/datadir/test_rclone_args-xeqaxir7/ *
2025/07/22 04:59:29 NOTICE: (17)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/
2025/07/22 04:59:31 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 04:59:31 NOTICE: (18)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file2.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/
2025/07/22 04:59:33 INFO  : file2.txt: Copied (replaced existing)
2025/07/22 04:59:33 NOTICE: (19)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file20.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir
2025/07/22 04:59:35 INFO  : file20.txt: Copied (replaced existing)
2025/07/22 04:59:35 NOTICE: (20)  : copy-as /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file21.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file1.txt
2025/07/22 04:59:36 INFO  : file21.txt: Copied (replaced existing) to: file1.txt
2025/07/22 04:59:36 NOTICE: (21)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/ file1.txt
2025/07/22 04:59:37 NOTICE: (22)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file2.txt
2025/07/22 04:59:38 NOTICE: (23)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/ file20.txt
2025/07/22 04:59:39 NOTICE: (24)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir/ file21.txt
2025/07/22 04:59:39 NOTICE: (25)  : bisync size-only
2025/07/22 04:59:45 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:46 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:46 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:59:46 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:59:46 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:46 INFO  : Building Path1 and Path2 listings
2025/07/22 04:59:47 INFO  : Path1 checking for diffs
2025/07/22 04:59:47 INFO  : Path2 checking for diffs
2025/07/22 04:59:47 INFO  : - Path2    File changed: size (smaller) - file1.txt
2025/07/22 04:59:47 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 04:59:47 INFO  : (Modified:    0 larger,    1 smaller)
2025/07/22 04:59:47 INFO  : Applying changes
2025/07/22 04:59:47 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/file1.txt
2025/07/22 04:59:47 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 04:59:49 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 04:59:49 INFO  : Updating listings
2025/07/22 04:59:49 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:49 INFO  : Bisync successful
2025/07/22 04:59:49 NOTICE: (26)  : bisync resync
2025/07/22 04:59:54 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 04:59:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 04:59:55 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:55 INFO  : Copying Path2 files to Path1
2025/07/22 04:59:55 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 04:59:56 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 04:59:57 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 04:59:58 INFO  : subdir/file21.txt: Copied (server-side copy)
2025/07/22 04:59:58 INFO  : subdir/file20.txt: Copied (server-side copy)
2025/07/22 04:59:58 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 04:59:58 INFO  : Resync updating listings
2025/07/22 04:59:58 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 04:59:58 INFO  : Bisync successful
2025/07/22 04:59:58 NOTICE: (27)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/
2025/07/22 05:00:00 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 05:00:00 NOTICE: (28)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file2.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/
2025/07/22 05:00:01 INFO  : file2.txt: Copied (replaced existing)
2025/07/22 05:00:01 NOTICE: (29)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file20.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir
2025/07/22 05:00:01 NOTICE: (30)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file21.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir
2025/07/22 05:00:03 INFO  : file21.txt: Copied (replaced existing)
2025/07/22 05:00:03 NOTICE: (31)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/ file1.txt
2025/07/22 05:00:04 NOTICE: (32)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file2.txt
2025/07/22 05:00:04 NOTICE: (33)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/ file20.txt
2025/07/22 05:00:05 NOTICE: (34)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir/ file21.txt
2025/07/22 05:00:06 NOTICE: (35)  : bisync ignore-size
2025/07/22 05:00:11 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:13 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:13 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:00:13 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": false,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:00:13 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:00:13 INFO  : Building Path1 and Path2 listings
2025/07/22 05:00:13 INFO  : Path1 checking for diffs
2025/07/22 05:00:13 INFO  : Path2 checking for diffs
2025/07/22 05:00:13 INFO  : - Path2    File changed: time (newer) - file2.txt
2025/07/22 05:00:13 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/07/22 05:00:13 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/07/22 05:00:13 INFO  : (Modified:    2 newer,    0 older)
2025/07/22 05:00:13 INFO  : Applying changes
2025/07/22 05:00:13 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/file2.txt
2025/07/22 05:00:13 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/file21.txt
2025/07/22 05:00:13 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:00:15 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:00:15 INFO  : subdir/file21.txt: Copied (server-side copy)
2025/07/22 05:00:15 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:15 INFO  : Updating listings
2025/07/22 05:00:15 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:00:15 INFO  : Bisync successful
2025/07/22 05:00:15 NOTICE: (36)  : bisync resync compare-all
2025/07/22 05:00:22 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:23 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 05:00:23 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:00:23 INFO  : Copying Path2 files to Path1
2025/07/22 05:00:23 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:00:23 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 05:00:32 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:00:36 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:00:40 INFO  : Resync updating listings
2025/07/22 05:00:40 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:00:40 INFO  : Bisync successful
2025/07/22 05:00:40 NOTICE: (37)  : copy-as /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file21.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file2.txt
2025/07/22 05:00:41 INFO  : file21.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:00:41 NOTICE: (38)  : touch-glob 2023-08-26 /tmp/045205be/datadir/test_rclone_args-xeqaxir7/ *
2025/07/22 05:00:41 NOTICE: (39)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file1.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/
2025/07/22 05:00:42 INFO  : file1.txt: Copied (replaced existing)
2025/07/22 05:00:42 NOTICE: (40)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file20.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir
2025/07/22 05:00:44 INFO  : file20.txt: Copied (replaced existing)
2025/07/22 05:00:44 NOTICE: (41)  : copy-file /tmp/045205be/datadir/test_rclone_args-xeqaxir7/file21.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir
2025/07/22 05:00:45 INFO  : file21.txt: Copied (replaced existing)
2025/07/22 05:00:45 NOTICE: (42)  : touch-glob 2007-07-23 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/ file2.txt
2025/07/22 05:00:46 NOTICE: (43)  : touch-glob 2023-08-26 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/ file1.txt
2025/07/22 05:00:47 NOTICE: (44)  : touch-glob 2023-08-26 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/ file20.txt
2025/07/22 05:00:48 NOTICE: (45)  : touch-glob 2023-08-26 TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir/ file21.txt
2025/07/22 05:00:48 NOTICE: (46)  : bisync compare-all
2025/07/22 05:00:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:56 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:00:56 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/07/22 05:00:56 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:00:56 INFO  : Building Path1 and Path2 listings
2025/07/22 05:00:56 INFO  : TestCryptDrive: Downloading hashes...
2025/07/22 05:01:04 INFO  : Path1 checking for diffs
2025/07/22 05:01:04 INFO  : - Path1    File changed: time (newer) - file1.txt
2025/07/22 05:01:04 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2025/07/22 05:01:04 INFO  : Path1:    2 changes:    0 new,    2 modified,    0 deleted
2025/07/22 05:01:04 INFO  : (Modified:    2 newer,    0 older)
2025/07/22 05:01:04 INFO  : Path2 checking for diffs
2025/07/22 05:01:04 INFO  : - Path2    File changed: size (smaller), hash - file2.txt
2025/07/22 05:01:04 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/07/22 05:01:04 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/07/22 05:01:04 INFO  : (Modified:    1 newer,    0 older,    0 larger,    1 smaller,    1 hash differs)
2025/07/22 05:01:04 INFO  : Applying changes
2025/07/22 05:01:04 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/file1.txt
2025/07/22 05:01:04 INFO  : - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/subdir/file20.txt
2025/07/22 05:01:04 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/file2.txt
2025/07/22 05:01:04 INFO  : - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/subdir/file21.txt
2025/07/22 05:01:04 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:01:08 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:01:09 INFO  : subdir/file21.txt: Copied (server-side copy)
2025/07/22 05:01:10 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:01:10 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:01:13 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:01:15 INFO  : subdir/file20.txt: Copied (server-side copy)
2025/07/22 05:01:15 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:01:15 INFO  : Updating listings
2025/07/22 05:01:15 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rclone_args/path2/"
2025/07/22 05:01:15 INFO  : Bisync successful
    bisync_test.go:560: TEST rclone_args PASSED
=== RUN   TestBisyncRemoteRemote/resolve
2025/07/22 05:01:16 INFO  : path1: Making directory
2025/07/22 05:01:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1"
2025/07/22 05:01:16 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/oq16m7v0dbfgrif5b069sbpaf0/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 05:01:18 INFO  : path2: Making directory
2025/07/22 05:01:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2"
2025/07/22 05:01:18 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/oq16m7v0dbfgrif5b069sbpaf0/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 05:01:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial"
2025/07/22 05:01:21 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_resolve-bugiroh6"
2025/07/22 05:01:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : Local file system at /tmp/045205be/initdir/test_resolve-bugiroh6: Waiting for checks to finish
2025/07/22 05:01:21 DEBUG : Local file system at /tmp/045205be/initdir/test_resolve-bugiroh6: Waiting for transfers to finish
2025/07/22 05:01:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:01:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:01:21 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 05:01:21 INFO  : file1.txt: Copied (new)
2025/07/22 05:01:21 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 05:01:21 INFO  : file2.txt: Copied (new)
2025/07/22 05:01:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 05:01:21 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 05:01:21 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:01:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles"
2025/07/22 05:01:21 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_resolve-laluduq1"
2025/07/22 05:01:21 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:21 DEBUG : Local file system at /tmp/045205be/datadir/test_resolve-laluduq1: Waiting for checks to finish
2025/07/22 05:01:21 DEBUG : Local file system at /tmp/045205be/datadir/test_resolve-laluduq1: Waiting for transfers to finish
2025/07/22 05:01:21 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/07/22 05:01:21 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/07/22 05:01:21 DEBUG : file2.txt.cc713425.partial: renamed to: file2.txt
2025/07/22 05:01:21 INFO  : file2.txt: Copied (new)
2025/07/22 05:01:21 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK
2025/07/22 05:01:21 DEBUG : file1L.txt.711f2f3a.partial: renamed to: file1L.txt
2025/07/22 05:01:21 INFO  : file1L.txt: Copied (new)
2025/07/22 05:01:21 DEBUG : file1R.txt.eafd8cfe.partial: renamed to: file1R.txt
2025/07/22 05:01:21 INFO  : file1R.txt: Copied (new)
2025/07/22 05:01:21 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_resolve-bugiroh6
2025/07/22 05:01:22 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:01:22 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:22 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1': Waiting for checks to finish
2025/07/22 05:01:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1': Waiting for transfers to finish
2025/07/22 05:01:25 DEBUG : RCLONE_TEST: md5 = 94a68cff3112ce49f0c2697799cf9d41 OK
2025/07/22 05:01:25 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:01:25 DEBUG : file2.txt: md5 = 5943e0924c0074d3715f8fc15045bb08 OK
2025/07/22 05:01:25 INFO  : file2.txt: Copied (new)
2025/07/22 05:01:25 DEBUG : file1.txt: md5 = 01e52a37a294578308c55b40ea5e77dd OK
2025/07/22 05:01:25 INFO  : file1.txt: Copied (new)
2025/07/22 05:01:25 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1'
2025/07/22 05:01:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:01:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:26 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:01:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Waiting for checks to finish
2025/07/22 05:01:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Waiting for transfers to finish
2025/07/22 05:01:28 DEBUG : RCLONE_TEST: md5 = 2da4167b9e955193b9149d52c309d30b OK
2025/07/22 05:01:28 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:01:28 DEBUG : file2.txt: md5 = e4152177133cd8c48bb895129c2a3c19 OK
2025/07/22 05:01:28 INFO  : file2.txt: Copied (new)
2025/07/22 05:01:28 DEBUG : file1.txt: md5 = c020f1b4445ce0aaf599be6c74a6c4d3 OK
2025/07/22 05:01:28 INFO  : file1.txt: Copied (new)
2025/07/22 05:01:28 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2'
2025/07/22 05:01:28 NOTICE: (01)  : test resolve
2025/07/22 05:01:28 NOTICE: (02)  : test initial bisync
2025/07/22 05:01:28 NOTICE: (03)  : bisync resync
2025/07/22 05:01:33 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:01:33 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:01:33 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:01:33 INFO  : Copying Path2 files to Path1
2025/07/22 05:01:33 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:01:33 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:01:34 INFO  : Resync updating listings
2025/07/22 05:01:34 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:01:34 INFO  : Bisync successful
2025/07/22 05:01:34 NOTICE: (04)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:01:34 NOTICE: (05)  : touch-glob 2001-01-02 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:01:34 NOTICE: (06)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:01:35 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:01:35 NOTICE: (07)  : touch-glob 2001-03-04 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:01:35 NOTICE: (08)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:01:37 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:01:37 NOTICE: (09)  : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato
2025/07/22 05:01:37 NOTICE: (10)  : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato
2025/07/22 05:01:42 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:01:42 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:01:42 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:01:42 INFO  : Building Path1 and Path2 listings
2025/07/22 05:01:42 INFO  : Path1 checking for diffs
2025/07/22 05:01:42 INFO  : - Path1    File changed: size (larger), time (newer) - file1.txt
2025/07/22 05:01:42 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 05:01:42 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/07/22 05:01:42 INFO  : Path2 checking for diffs
2025/07/22 05:01:42 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/07/22 05:01:42 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 05:01:42 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/07/22 05:01:42 INFO  : Applying changes
2025/07/22 05:01:42 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:01:42 INFO  : Checking potential conflicts...
2025/07/22 05:01:44 ERROR : file1.txt: md5 differ
2025/07/22 05:01:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 differences found
2025/07/22 05:01:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 errors while checking
2025/07/22 05:01:44 INFO  : Finished checking the potential conflicts. 1 differences found
2025/07/22 05:01:44 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:01:44 INFO  : file1.txt: Path1 is newer. Path1: 2001-03-04 00:00:00 +0000 UTC, Path2: 2001-01-02 00:00:00 +0000 UTC, Difference: 1464h0m0s
2025/07/22 05:01:44 INFO  : file1.txt: The winner is: Path1
2025/07/22 05:01:44 NOTICE: - Path2    Deleting Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt
2025/07/22 05:01:45 INFO  : file1.txt: Deleted
2025/07/22 05:01:45 INFO  : - Path1    Queue copy to Path2       - file1.txt
2025/07/22 05:01:45 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:01:46 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:01:46 INFO  : Updating listings
2025/07/22 05:01:46 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:01:46 INFO  : Bisync successful
2025/07/22 05:01:46 NOTICE: (11)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:01:46 NOTICE: (12)  : touch-glob 2001-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:01:46 NOTICE: (13)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:01:48 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:01:48 NOTICE: (14)  : touch-glob 2001-08-26 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:01:48 NOTICE: (15)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:01:49 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:01:49 NOTICE: (16)  : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur
2025/07/22 05:01:49 NOTICE: (17)  : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur
2025/07/22 05:01:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:01:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:01:54 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:01:54 INFO  : Building Path1 and Path2 listings
2025/07/22 05:01:54 INFO  : Path1 checking for diffs
2025/07/22 05:01:54 INFO  : - Path1    File changed: time (newer) - file1.txt
2025/07/22 05:01:54 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 05:01:54 INFO  : (Modified:    1 newer,    0 older)
2025/07/22 05:01:54 INFO  : Path2 checking for diffs
2025/07/22 05:01:54 INFO  : - Path2    File changed: time (newer) - file1.txt
2025/07/22 05:01:54 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/07/22 05:01:54 INFO  : (Modified:    1 newer,    0 older)
2025/07/22 05:01:54 INFO  : Applying changes
2025/07/22 05:01:54 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:01:54 INFO  : Checking potential conflicts...
2025/07/22 05:01:56 ERROR : file1.txt: md5 differ
2025/07/22 05:01:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 differences found
2025/07/22 05:01:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 errors while checking
2025/07/22 05:01:56 INFO  : Finished checking the potential conflicts. 1 differences found
2025/07/22 05:01:56 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:01:56 INFO  : file1.txt: The winner is: Path2
2025/07/22 05:01:56 NOTICE: - Path1    Renaming Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt.dinosaur1
2025/07/22 05:01:57 INFO  : file1.txt: Moved (server-side) to: file1.txt.dinosaur1
2025/07/22 05:01:57 NOTICE: - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt.dinosaur1
2025/07/22 05:01:57 NOTICE: - Path2    Not renaming Path2 copy, as it was determined the winner - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt
2025/07/22 05:01:57 NOTICE: - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt
2025/07/22 05:01:57 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:01:58 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:01:58 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:02:00 INFO  : file1.txt.dinosaur1: Copied (server-side copy)
2025/07/22 05:02:00 INFO  : Updating listings
2025/07/22 05:02:00 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:00 INFO  : Bisync successful
2025/07/22 05:02:00 NOTICE: (18)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:02:00 NOTICE: (19)  : touch-glob 2002-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:02:00 NOTICE: (20)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:02:01 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:02:01 NOTICE: (21)  : touch-glob 2002-08-26 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:02:01 NOTICE: (22)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:02:03 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:02:03 NOTICE: (23)  : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple
2025/07/22 05:02:03 NOTICE: (24)  : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple
2025/07/22 05:02:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:02:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:02:08 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:08 INFO  : Building Path1 and Path2 listings
2025/07/22 05:02:08 INFO  : Path1 checking for diffs
2025/07/22 05:02:08 INFO  : - Path1    File is new               - file1.txt
2025/07/22 05:02:08 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:08 INFO  : Path2 checking for diffs
2025/07/22 05:02:08 INFO  : - Path2    File is new               - file1.txt
2025/07/22 05:02:08 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:08 INFO  : Applying changes
2025/07/22 05:02:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:02:08 INFO  : Checking potential conflicts...
2025/07/22 05:02:10 ERROR : file1.txt: md5 differ
2025/07/22 05:02:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 differences found
2025/07/22 05:02:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 errors while checking
2025/07/22 05:02:10 INFO  : Finished checking the potential conflicts. 1 differences found
2025/07/22 05:02:10 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:02:10 INFO  : file1.txt: Winner cannot be determined as sizes are equal. Path1: 33, Path2: 33, Difference: 0
2025/07/22 05:02:10 INFO  : file1.txt: A winner could not be determined.
2025/07/22 05:02:10 NOTICE: - Path1    Renaming Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt.apple1
2025/07/22 05:02:11 INFO  : file1.txt: Moved (server-side) to: file1.txt.apple1
2025/07/22 05:02:11 NOTICE: - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt.apple1
2025/07/22 05:02:11 NOTICE: - Path2    Renaming Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt.apple2
2025/07/22 05:02:13 INFO  : file1.txt: Moved (server-side) to: file1.txt.apple2
2025/07/22 05:02:13 NOTICE: - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt.apple2
2025/07/22 05:02:13 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:02:14 INFO  : file1.txt.apple2: Copied (server-side copy)
2025/07/22 05:02:14 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:02:15 INFO  : file1.txt.apple1: Copied (server-side copy)
2025/07/22 05:02:15 INFO  : Updating listings
2025/07/22 05:02:15 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:15 INFO  : Bisync successful
2025/07/22 05:02:15 NOTICE: (25)  : test different suffixes
2025/07/22 05:02:15 NOTICE: (26)  : touch-glob 2003-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:02:15 NOTICE: (27)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:02:17 INFO  : file1R.txt: Copied (new) to: file1.txt
2025/07/22 05:02:17 NOTICE: (28)  : touch-glob 2003-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:02:17 NOTICE: (29)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:02:19 INFO  : file1L.txt: Copied (new) to: file1.txt
2025/07/22 05:02:19 NOTICE: (30)  : test bisync run with --conflict-resolve=older --conflict-loser=num --conflict-suffix=cloud,local
2025/07/22 05:02:19 NOTICE: (31)  : bisync conflict-resolve=older conflict-loser=num conflict-suffix=cloud,local
2025/07/22 05:02:23 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:02:23 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:02:23 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:23 INFO  : Building Path1 and Path2 listings
2025/07/22 05:02:24 INFO  : Path1 checking for diffs
2025/07/22 05:02:24 INFO  : - Path1    File is new               - file1.txt
2025/07/22 05:02:24 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:24 INFO  : Path2 checking for diffs
2025/07/22 05:02:24 INFO  : - Path2    File is new               - file1.txt
2025/07/22 05:02:24 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:24 INFO  : Applying changes
2025/07/22 05:02:24 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:02:24 INFO  : Checking potential conflicts...
2025/07/22 05:02:26 ERROR : file1.txt: md5 differ
2025/07/22 05:02:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 differences found
2025/07/22 05:02:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 errors while checking
2025/07/22 05:02:26 INFO  : Finished checking the potential conflicts. 1 differences found
2025/07/22 05:02:26 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:02:26 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
2025/07/22 05:02:26 INFO  : file1.txt: A winner could not be determined.
2025/07/22 05:02:26 NOTICE: - Path1    Renaming Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt.cloud1
2025/07/22 05:02:27 INFO  : file1.txt: Moved (server-side) to: file1.txt.cloud1
2025/07/22 05:02:27 NOTICE: - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt.cloud1
2025/07/22 05:02:27 NOTICE: - Path2    Renaming Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt.local1
2025/07/22 05:02:28 INFO  : file1.txt: Moved (server-side) to: file1.txt.local1
2025/07/22 05:02:28 NOTICE: - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt.local1
2025/07/22 05:02:28 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:02:29 INFO  : file1.txt.local1: Copied (server-side copy)
2025/07/22 05:02:29 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:02:30 INFO  : file1.txt.cloud1: Copied (server-side copy)
2025/07/22 05:02:30 INFO  : Updating listings
2025/07/22 05:02:30 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:30 INFO  : Bisync successful
2025/07/22 05:02:30 NOTICE: (32)  : test legacy
2025/07/22 05:02:30 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:02:30 NOTICE: (34)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:02:32 INFO  : file1R.txt: Copied (new) to: file1.txt
2025/07/22 05:02:32 NOTICE: (35)  : touch-glob 2004-07-23 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:02:32 NOTICE: (36)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:02:34 INFO  : file1L.txt: Copied (new) to: file1.txt
2025/07/22 05:02:34 NOTICE: (37)  : test bisync run with --conflict-resolve=none --conflict-loser=pathname --conflict-suffix=.path
2025/07/22 05:02:34 NOTICE: (38)  : bisync conflict-resolve=none conflict-loser=pathname conflict-suffix=.path
2025/07/22 05:02:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:02:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:02:39 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:39 INFO  : Building Path1 and Path2 listings
2025/07/22 05:02:39 INFO  : Path1 checking for diffs
2025/07/22 05:02:39 INFO  : - Path1    File is new               - file1.txt
2025/07/22 05:02:39 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:39 INFO  : Path2 checking for diffs
2025/07/22 05:02:39 INFO  : - Path2    File is new               - file1.txt
2025/07/22 05:02:39 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2025/07/22 05:02:39 INFO  : Applying changes
2025/07/22 05:02:39 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:02:39 INFO  : Checking potential conflicts...
2025/07/22 05:02:41 ERROR : file1.txt: md5 differ
2025/07/22 05:02:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 differences found
2025/07/22 05:02:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 1 errors while checking
2025/07/22 05:02:41 INFO  : Finished checking the potential conflicts. 1 differences found
2025/07/22 05:02:41 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:02:41 NOTICE: - Path1    Renaming Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt..path1
2025/07/22 05:02:42 INFO  : file1.txt: Moved (server-side) to: file1.txt..path1
2025/07/22 05:02:42 NOTICE: - Path1    Queue copy to Path2       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt..path1
2025/07/22 05:02:42 NOTICE: - Path2    Renaming Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt..path2
2025/07/22 05:02:43 INFO  : file1.txt: Moved (server-side) to: file1.txt..path2
2025/07/22 05:02:43 NOTICE: - Path2    Queue copy to Path1       - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file1.txt..path2
2025/07/22 05:02:43 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:02:44 INFO  : file1.txt..path2: Copied (server-side copy)
2025/07/22 05:02:44 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:02:46 INFO  : file1.txt..path1: Copied (server-side copy)
2025/07/22 05:02:46 INFO  : Updating listings
2025/07/22 05:02:46 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:46 INFO  : Bisync successful
2025/07/22 05:02:46 NOTICE: (39)  : test deletes on both sides with default suffix
2025/07/22 05:02:46 NOTICE: (40)  : touch-glob 2005-01-02 /tmp/045205be/datadir/test_resolve-laluduq1/ file1R.txt
2025/07/22 05:02:46 NOTICE: (41)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file1.txt
2025/07/22 05:02:47 INFO  : file1R.txt: Copied (new) to: file1.txt
2025/07/22 05:02:47 NOTICE: (42)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file2.txt
2025/07/22 05:02:49 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:02:49 NOTICE: (43)  : touch-glob 2006-03-04 /tmp/045205be/datadir/test_resolve-laluduq1/ file1L.txt
2025/07/22 05:02:49 NOTICE: (44)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/ file1.txt
2025/07/22 05:02:50 INFO  : file1L.txt: Copied (new) to: file1.txt
2025/07/22 05:02:50 NOTICE: (45)  : copy-as /tmp/045205be/datadir/test_resolve-laluduq1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/ file2.txt
2025/07/22 05:02:52 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:02:52 NOTICE: (46)  : test bisync run with --conflict-resolve=newer --conflict-loser=delete
2025/07/22 05:02:52 NOTICE: (47)  : bisync conflict-resolve=newer conflict-loser=delete
2025/07/22 05:02:57 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:02:57 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:02:57 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:02:57 INFO  : Building Path1 and Path2 listings
2025/07/22 05:02:57 INFO  : Path1 checking for diffs
2025/07/22 05:02:57 INFO  : - Path1    File changed: size (larger), time (newer) - file2.txt
2025/07/22 05:02:57 INFO  : - Path1    File is new               - file1.txt
2025/07/22 05:02:57 INFO  : Path1:    2 changes:    1 new,    1 modified,    0 deleted
2025/07/22 05:02:57 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/07/22 05:02:57 INFO  : Path2 checking for diffs
2025/07/22 05:02:57 INFO  : - Path2    File changed: size (larger), time (newer) - file2.txt
2025/07/22 05:02:57 INFO  : - Path2    File is new               - file1.txt
2025/07/22 05:02:57 INFO  : Path2:    2 changes:    1 new,    1 modified,    0 deleted
2025/07/22 05:02:57 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/07/22 05:02:57 INFO  : Applying changes
2025/07/22 05:02:57 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:02:57 INFO  : Checking potential conflicts...
2025/07/22 05:02:59 ERROR : file1.txt: md5 differ
2025/07/22 05:03:00 ERROR : file2.txt: md5 differ
2025/07/22 05:03:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 2 differences found
2025/07/22 05:03:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2': 2 errors while checking
2025/07/22 05:03:00 INFO  : Finished checking the potential conflicts. 2 differences found
2025/07/22 05:03:00 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2025/07/22 05:03:00 INFO  : file1.txt: Path1 is newer. Path1: 2006-03-04 00:00:00 +0000 UTC, Path2: 2005-01-02 00:00:00 +0000 UTC, Difference: 10224h0m0s
2025/07/22 05:03:00 INFO  : file1.txt: The winner is: Path1
2025/07/22 05:03:00 NOTICE: - Path2    Deleting Path2 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/file1.txt
2025/07/22 05:03:00 INFO  : file1.txt: Deleted
2025/07/22 05:03:00 INFO  : - Path1    Queue copy to Path2       - file1.txt
2025/07/22 05:03:00 NOTICE: - WARNING  New or changed in both paths       - file2.txt
2025/07/22 05:03:00 INFO  : file2.txt: Path2 is newer. Path1: 2005-01-02 00:00:00 +0000 UTC, Path2: 2006-03-04 00:00:00 +0000 UTC, Difference: 10224h0m0s
2025/07/22 05:03:00 INFO  : file2.txt: The winner is: Path2
2025/07/22 05:03:00 NOTICE: - Path1    Deleting Path1 copy                - TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/file2.txt
2025/07/22 05:03:01 INFO  : file2.txt: Deleted
2025/07/22 05:03:01 INFO  : - Path2    Queue copy to Path1       - file2.txt
2025/07/22 05:03:01 INFO  : - Path2    Do queued copies to                - Path1
2025/07/22 05:03:02 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:03:02 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:03:04 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:03:04 INFO  : Updating listings
2025/07/22 05:03:04 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1': Crypt detected! Using cryptcheck instead of check. (Use --size-only or --ignore-checksum to disable)
2025/07/22 05:03:05 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resolve/path2/"
2025/07/22 05:03:05 INFO  : Bisync successful
    bisync_test.go:560: TEST resolve PASSED
=== RUN   TestBisyncRemoteRemote/resync
2025/07/22 05:03:06 INFO  : path1: Making directory
2025/07/22 05:03:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1"
2025/07/22 05:03:06 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/dfoecmi82rcood27dsu6dlrcss/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 05:03:08 INFO  : path2: Making directory
2025/07/22 05:03:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2"
2025/07/22 05:03:08 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/dfoecmi82rcood27dsu6dlrcss/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 05:03:10 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/07/22 05:03:10 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_resync-wometos4"
2025/07/22 05:03:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : Local file system at /tmp/045205be/initdir/test_resync-wometos4: Waiting for checks to finish
2025/07/22 05:03:10 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : Local file system at /tmp/045205be/initdir/test_resync-wometos4: Waiting for transfers to finish
2025/07/22 05:03:10 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 05:03:10 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 INFO  : file2.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/07/22 05:03:10 INFO  : file3.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 05:03:10 INFO  : file1.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/07/22 05:03:10 INFO  : file4.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:03:10 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/07/22 05:03:10 INFO  : file7.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/07/22 05:03:10 INFO  : file5.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/07/22 05:03:10 INFO  : file6.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 05:03:10 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 05:03:10 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:03:10 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/07/22 05:03:10 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_resync-yulajil6"
2025/07/22 05:03:10 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:10 DEBUG : Local file system at /tmp/045205be/datadir/test_resync-yulajil6: Waiting for checks to finish
2025/07/22 05:03:10 DEBUG : Local file system at /tmp/045205be/datadir/test_resync-yulajil6: Waiting for transfers to finish
2025/07/22 05:03:10 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:03:10 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt
2025/07/22 05:03:10 INFO  : fileA.txt: Copied (new)
2025/07/22 05:03:10 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:03:10 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt
2025/07/22 05:03:10 INFO  : fileB.txt: Copied (new)
2025/07/22 05:03:11 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_resync-wometos4
2025/07/22 05:03:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1': Waiting for checks to finish
2025/07/22 05:03:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1': Waiting for transfers to finish
2025/07/22 05:03:14 DEBUG : RCLONE_TEST: md5 = 2576a6b66c6671daf33017540c96c0c7 OK
2025/07/22 05:03:14 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:03:14 DEBUG : file3.txt: md5 = deeb88f35a55116bfb3e0b062fcac5f8 OK
2025/07/22 05:03:14 INFO  : file3.txt: Copied (new)
2025/07/22 05:03:14 DEBUG : file1.txt: md5 = b57783f87b6b9bcc92e202173996966f OK
2025/07/22 05:03:14 INFO  : file1.txt: Copied (new)
2025/07/22 05:03:14 DEBUG : file2.txt: md5 = f827ef68a724001cb42f96dc17401e49 OK
2025/07/22 05:03:14 INFO  : file2.txt: Copied (new)
2025/07/22 05:03:16 DEBUG : file4.txt: md5 = 4e3edb0200056f1b023cea108b625887 OK
2025/07/22 05:03:16 INFO  : file4.txt: Copied (new)
2025/07/22 05:03:16 DEBUG : file6.txt: md5 = 62b2293c29514129ecdb7c8a3be73197 OK
2025/07/22 05:03:16 INFO  : file6.txt: Copied (new)
2025/07/22 05:03:16 DEBUG : file7.txt: md5 = cc1d1fc18fa6857804ab6b1660399e40 OK
2025/07/22 05:03:16 INFO  : file7.txt: Copied (new)
2025/07/22 05:03:16 DEBUG : file5.txt: md5 = a63ec3c9a8edeb67bd110724f6d93d81 OK
2025/07/22 05:03:16 INFO  : file5.txt: Copied (new)
2025/07/22 05:03:16 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1'
2025/07/22 05:03:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:03:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2': Waiting for checks to finish
2025/07/22 05:03:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2': Waiting for transfers to finish
2025/07/22 05:03:19 DEBUG : file2.txt: md5 = 96b610a1f834cd24eb21a240912a36e5 OK
2025/07/22 05:03:19 INFO  : file2.txt: Copied (new)
2025/07/22 05:03:19 DEBUG : RCLONE_TEST: md5 = c8de5f7d3ab53e4a1c45ed4a34fa69e4 OK
2025/07/22 05:03:19 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:03:19 DEBUG : file3.txt: md5 = 3b05307432dd676168172b387798d3b0 OK
2025/07/22 05:03:19 INFO  : file3.txt: Copied (new)
2025/07/22 05:03:19 DEBUG : file1.txt: md5 = 22eccc7e337ae8dee747e51b2eefe9b7 OK
2025/07/22 05:03:19 INFO  : file1.txt: Copied (new)
2025/07/22 05:03:20 DEBUG : file4.txt: md5 = 598b3823471dd933cf10d7c2906238b2 OK
2025/07/22 05:03:20 INFO  : file4.txt: Copied (new)
2025/07/22 05:03:20 DEBUG : file5.txt: md5 = 5f2b3dc697d082fb47e1051d979f6361 OK
2025/07/22 05:03:20 INFO  : file5.txt: Copied (new)
2025/07/22 05:03:20 DEBUG : file6.txt: md5 = 0e609c54ce5e4f62d72686a0135c61ed OK
2025/07/22 05:03:20 INFO  : file6.txt: Copied (new)
2025/07/22 05:03:20 DEBUG : file7.txt: md5 = a026c189dd0279631c7398f00486d989 OK
2025/07/22 05:03:20 INFO  : file7.txt: Copied (new)
2025/07/22 05:03:20 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2'
2025/07/22 05:03:20 NOTICE: (01)  : test resync
2025/07/22 05:03:20 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/07/22 05:03:20 NOTICE: (03)  : purge-children TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/
2025/07/22 05:03:25 NOTICE: (04)  : bisync resync
2025/07/22 05:03:30 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:03:30 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:03:30 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:03:30 INFO  : Copying Path2 files to Path1
2025/07/22 05:03:30 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:03:31 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/07/22 05:03:31 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:03:31 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:03:31 INFO  : file3.txt: Copied (server-side copy)
2025/07/22 05:03:32 INFO  : file4.txt: Copied (server-side copy)
2025/07/22 05:03:32 INFO  : file5.txt: Copied (server-side copy)
2025/07/22 05:03:32 INFO  : file6.txt: Copied (server-side copy)
2025/07/22 05:03:32 INFO  : file7.txt: Copied (server-side copy)
2025/07/22 05:03:32 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:03:32 INFO  : Resync updating listings
2025/07/22 05:03:32 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:03:32 INFO  : Bisync successful
2025/07/22 05:03:32 NOTICE: (05)  : move-listings empty-path1
2025/07/22 05:03:32 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/07/22 05:03:32 NOTICE: (07)  : purge-children TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/
2025/07/22 05:03:36 NOTICE: (08)  : bisync resync
2025/07/22 05:03:41 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:03:41 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:03:41 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:03:41 INFO  : Copying Path2 files to Path1
2025/07/22 05:03:41 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:03:41 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:03:42 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/07/22 05:03:42 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:03:42 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:03:43 INFO  : file3.txt: Copied (server-side copy)
2025/07/22 05:03:43 INFO  : file4.txt: Copied (server-side copy)
2025/07/22 05:03:43 INFO  : file5.txt: Copied (server-side copy)
2025/07/22 05:03:43 INFO  : file6.txt: Copied (server-side copy)
2025/07/22 05:03:44 INFO  : file7.txt: Copied (server-side copy)
2025/07/22 05:03:44 INFO  : Resync updating listings
2025/07/22 05:03:44 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:03:44 INFO  : Bisync successful
2025/07/22 05:03:44 NOTICE: (09)  : move-listings empty-path2
2025/07/22 05:03:44 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/07/22 05:03:44 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/045205be/datadir/test_resync-yulajil6/ fileA.txt
2025/07/22 05:03:44 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/045205be/datadir/test_resync-yulajil6/ fileB.txt
2025/07/22 05:03:44 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/07/22 05:03:44 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/07/22 05:03:44 NOTICE: (15)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/file1.txt
2025/07/22 05:03:44 INFO  : file1.txt: Deleted
2025/07/22 05:03:44 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/07/22 05:03:44 NOTICE: (17)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/file2.txt
2025/07/22 05:03:45 INFO  : file2.txt: Deleted
2025/07/22 05:03:45 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/07/22 05:03:45 NOTICE: (19)  : copy-as /tmp/045205be/datadir/test_resync-yulajil6/fileA.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/ file3.txt
2025/07/22 05:03:47 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/07/22 05:03:47 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/07/22 05:03:47 NOTICE: (21)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/file4.txt
2025/07/22 05:03:47 INFO  : file4.txt: Deleted
2025/07/22 05:03:47 NOTICE: (22)  : copy-as /tmp/045205be/datadir/test_resync-yulajil6/fileA.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/ file4.txt
2025/07/22 05:03:49 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/07/22 05:03:49 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/07/22 05:03:49 NOTICE: (24)  : copy-as /tmp/045205be/datadir/test_resync-yulajil6/fileB.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/ file5.txt
2025/07/22 05:03:50 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/07/22 05:03:50 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/07/22 05:03:50 NOTICE: (26)  : copy-as /tmp/045205be/datadir/test_resync-yulajil6/fileB.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/ file6.txt
2025/07/22 05:03:52 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/07/22 05:03:52 NOTICE: (27)  : copy-as /tmp/045205be/datadir/test_resync-yulajil6/fileA.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/ file6.txt
2025/07/22 05:03:54 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/07/22 05:03:54 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/07/22 05:03:54 NOTICE: (29)  : test run bisync with resync
2025/07/22 05:03:54 NOTICE: (30)  : bisync resync
2025/07/22 05:03:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:03:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:03:59 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:03:59 INFO  : Copying Path2 files to Path1
2025/07/22 05:03:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:04:00 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:04:00 INFO  : file4.txt: Copied (server-side copy)
2025/07/22 05:04:00 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:04:01 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:04:02 INFO  : file3.txt: Copied (server-side copy)
2025/07/22 05:04:02 INFO  : file5.txt: Copied (server-side copy)
2025/07/22 05:04:02 INFO  : file6.txt: Copied (server-side copy)
2025/07/22 05:04:02 INFO  : Resync updating listings
2025/07/22 05:04:02 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:04:02 INFO  : Bisync successful
2025/07/22 05:04:02 NOTICE: (31)  : copy-listings mixed-diffs
2025/07/22 05:04:02 NOTICE: (32)  : test run normal bisync
2025/07/22 05:04:02 NOTICE: (33)  : bisync
2025/07/22 05:04:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:04:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:04:07 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:04:07 INFO  : Building Path1 and Path2 listings
2025/07/22 05:04:07 INFO  : Path1 checking for diffs
2025/07/22 05:04:07 INFO  : Path2 checking for diffs
2025/07/22 05:04:07 INFO  : No changes found
2025/07/22 05:04:07 INFO  : Updating listings
2025/07/22 05:04:07 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:04:07 INFO  : Bisync successful
2025/07/22 05:04:07 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/07/22 05:04:07 NOTICE: (35)  : purge-children TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/
2025/07/22 05:04:11 NOTICE: (36)  : bisync
2025/07/22 05:04:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:04:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:04:17 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync/path2/"
2025/07/22 05:04:17 INFO  : Building Path1 and Path2 listings
2025/07/22 05:04:17 INFO  : Path1 checking for diffs
2025/07/22 05:04:17 INFO  : Path2 checking for diffs
2025/07/22 05:04:17 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/045205be/workdir/TestCryptDrive_rclone-test-civitaf8qefe_045205be_resync_path1..TestCryptDrive_rclone-test-civitaf8qefe_045205be_resync_path2.path2.lst-new
2025/07/22 05:04:17 ERROR : Bisync critical error: empty current Path2 listing: /tmp/045205be/workdir/TestCryptDrive_rclone-test-civitaf8qefe_045205be_resync_path1..TestCryptDrive_rclone-test-civitaf8qefe_045205be_resync_path2.path2.lst-new
2025/07/22 05:04:17 ERROR : Bisync aborted. Must run --resync to recover.
2025/07/22 05:04:17 NOTICE: Bisync error: bisync aborted
    bisync_test.go:560: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/07/22 05:04:18 INFO  : path1: Making directory
2025/07/22 05:04:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1"
2025/07/22 05:04:18 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/p8bgtduumu3o6tfedl25miakvk/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 05:04:21 INFO  : path2: Making directory
2025/07/22 05:04:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2"
2025/07/22 05:04:21 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/p8bgtduumu3o6tfedl25miakvk/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 05:04:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/07/22 05:04:23 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_resync_modes-qotidez3"
2025/07/22 05:04:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : Local file system at /tmp/045205be/initdir/test_resync_modes-qotidez3: Waiting for checks to finish
2025/07/22 05:04:23 DEBUG : Local file system at /tmp/045205be/initdir/test_resync_modes-qotidez3: Waiting for transfers to finish
2025/07/22 05:04:23 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:04:23 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:04:23 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 05:04:23 INFO  : file1.txt: Copied (new)
2025/07/22 05:04:23 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 05:04:23 INFO  : file2.txt: Copied (new)
2025/07/22 05:04:23 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 05:04:23 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 05:04:23 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:04:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/07/22 05:04:23 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_resync_modes-towicof1"
2025/07/22 05:04:23 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:23 DEBUG : Local file system at /tmp/045205be/datadir/test_resync_modes-towicof1: Waiting for checks to finish
2025/07/22 05:04:23 DEBUG : Local file system at /tmp/045205be/datadir/test_resync_modes-towicof1: Waiting for transfers to finish
2025/07/22 05:04:23 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/07/22 05:04:23 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2025/07/22 05:04:23 INFO  : file2.txt: Copied (new)
2025/07/22 05:04:23 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/07/22 05:04:23 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2025/07/22 05:04:23 INFO  : file1L.txt: Copied (new)
2025/07/22 05:04:23 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/07/22 05:04:23 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2025/07/22 05:04:23 INFO  : file1R.txt: Copied (new)
2025/07/22 05:04:24 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_resync_modes-qotidez3
2025/07/22 05:04:24 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:04:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1': Waiting for checks to finish
2025/07/22 05:04:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1': Waiting for transfers to finish
2025/07/22 05:04:27 DEBUG : RCLONE_TEST: md5 = 385d0b25483ca2871638c42468c126a7 OK
2025/07/22 05:04:27 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:04:27 DEBUG : file1.txt: md5 = 5be3b8771e4c47fe2de96a9d3740384c OK
2025/07/22 05:04:27 INFO  : file1.txt: Copied (new)
2025/07/22 05:04:27 DEBUG : file2.txt: md5 = c18133974b418888b7059b2191bbcd36 OK
2025/07/22 05:04:27 INFO  : file2.txt: Copied (new)
2025/07/22 05:04:27 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1'
2025/07/22 05:04:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:04:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:28 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:04:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2': Waiting for checks to finish
2025/07/22 05:04:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2': Waiting for transfers to finish
2025/07/22 05:04:30 DEBUG : RCLONE_TEST: md5 = 53b9f24892b63889c03ba5ad3c2fe46f OK
2025/07/22 05:04:30 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:04:30 DEBUG : file1.txt: md5 = 87b60cbf0e5694d74b29c961600a2c4e OK
2025/07/22 05:04:30 INFO  : file1.txt: Copied (new)
2025/07/22 05:04:30 DEBUG : file2.txt: md5 = c2378a4a78c4cf207b5d373bc31578d0 OK
2025/07/22 05:04:30 INFO  : file2.txt: Copied (new)
2025/07/22 05:04:30 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2'
2025/07/22 05:04:31 NOTICE: (01)  : test resync-mode
2025/07/22 05:04:31 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:04:31 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:04:31 NOTICE: (04)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:04:32 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:32 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:04:32 NOTICE: (06)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:04:34 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:34 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/07/22 05:04:34 NOTICE: (08)  : bisync resync resync-mode=newer
2025/07/22 05:04:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:04:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:04:39 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:04:39 INFO  : Copying Path2 files to Path1
2025/07/22 05:04:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:04:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:04:41 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:04:41 INFO  : Resync updating listings
2025/07/22 05:04:41 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:04:41 INFO  : Bisync successful
2025/07/22 05:04:41 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:04:41 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:04:41 NOTICE: (11)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:04:42 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:42 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:04:42 NOTICE: (13)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:04:44 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:44 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/07/22 05:04:44 NOTICE: (15)  : bisync resync resync-mode=path2
2025/07/22 05:04:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:04:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:04:49 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:04:49 INFO  : Copying Path2 files to Path1
2025/07/22 05:04:49 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:04:51 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:04:51 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:04:51 INFO  : Resync updating listings
2025/07/22 05:04:51 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:04:51 INFO  : Bisync successful
2025/07/22 05:04:51 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:04:51 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:04:51 NOTICE: (18)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:04:52 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:52 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:04:52 NOTICE: (20)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:04:54 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:04:54 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/07/22 05:04:54 NOTICE: (22)  : bisync resync resync-mode=larger
2025/07/22 05:04:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:04:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:04:59 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:04:59 INFO  : Copying Path2 files to Path1
2025/07/22 05:04:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:04:59 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/07/22 05:05:00 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:05:00 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:05:01 INFO  : Resync updating listings
2025/07/22 05:05:01 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:01 INFO  : Bisync successful
2025/07/22 05:05:01 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:05:01 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:05:01 NOTICE: (25)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:05:02 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:02 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:05:02 NOTICE: (27)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:05:04 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:04 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/07/22 05:05:04 NOTICE: (29)  : bisync resync resync-mode=older
2025/07/22 05:05:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:05:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:05:09 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:09 INFO  : Copying Path2 files to Path1
2025/07/22 05:05:09 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:05:09 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/07/22 05:05:10 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:05:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:05:11 INFO  : Resync updating listings
2025/07/22 05:05:11 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:11 INFO  : Bisync successful
2025/07/22 05:05:11 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:05:11 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:05:11 NOTICE: (32)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:05:12 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:12 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:05:12 NOTICE: (34)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:05:14 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:14 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/07/22 05:05:14 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/07/22 05:05:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:05:19 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:05:19 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:19 INFO  : Copying Path2 files to Path1
2025/07/22 05:05:19 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:05:20 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/07/22 05:05:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:05:20 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/07/22 05:05:21 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:05:21 INFO  : Resync updating listings
2025/07/22 05:05:21 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:21 INFO  : Bisync successful
2025/07/22 05:05:21 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/07/22 05:05:21 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:05:21 NOTICE: (39)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:05:23 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:23 NOTICE: (40)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file2.txt
2025/07/22 05:05:24 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:05:24 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:05:24 NOTICE: (42)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:05:26 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:26 NOTICE: (43)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file2.txt
2025/07/22 05:05:27 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:05:27 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/07/22 05:05:27 NOTICE: (45)  : bisync resync resync-mode=path1
2025/07/22 05:05:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:05:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:05:32 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:32 INFO  : Copying Path2 files to Path1
2025/07/22 05:05:32 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:05:33 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:05:34 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:05:34 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:05:34 INFO  : Resync updating listings
2025/07/22 05:05:34 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:34 INFO  : Bisync successful
2025/07/22 05:05:34 NOTICE: (46)  : test no winner
2025/07/22 05:05:34 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1R.txt
2025/07/22 05:05:34 NOTICE: (48)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file1.txt
2025/07/22 05:05:36 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:36 NOTICE: (49)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1R.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file2.txt
2025/07/22 05:05:38 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:05:38 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/045205be/datadir/test_resync_modes-towicof1/ file1L.txt
2025/07/22 05:05:38 NOTICE: (51)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/ file1.txt
2025/07/22 05:05:39 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/07/22 05:05:39 NOTICE: (52)  : copy-as /tmp/045205be/datadir/test_resync_modes-towicof1/file1L.txt TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/ file2.txt
2025/07/22 05:05:41 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/07/22 05:05:41 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/07/22 05:05:41 NOTICE: (54)  : bisync resync resync-mode=newer
2025/07/22 05:05:46 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:05:46 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:05:46 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:46 INFO  : Copying Path2 files to Path1
2025/07/22 05:05:46 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:05:47 INFO  : file1.txt: Copied (server-side copy)
2025/07/22 05:05:47 INFO  : file2.txt: Copied (server-side copy)
2025/07/22 05:05:47 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:05:48 INFO  : Resync updating listings
2025/07/22 05:05:48 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/resync_modes/path2/"
2025/07/22 05:05:48 INFO  : Bisync successful
    bisync_test.go:560: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/07/22 05:05:49 INFO  : path1: Making directory
2025/07/22 05:05:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1"
2025/07/22 05:05:49 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/dv7afrs03vrmjf6n0cfhi7ai6k/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 05:05:51 INFO  : path2: Making directory
2025/07/22 05:05:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2"
2025/07/22 05:05:51 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/dv7afrs03vrmjf6n0cfhi7ai6k/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 05:05:53 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/07/22 05:05:53 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_rmdirs-yeravil6"
2025/07/22 05:05:53 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : subdir: Making directory with metadata
2025/07/22 05:05:53 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 05:05:53 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/07/22 05:05:53 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/07/22 05:05:53 INFO  : file1.copy1.txt: Copied (new)
2025/07/22 05:05:53 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : Local file system at /tmp/045205be/initdir/test_rmdirs-yeravil6: Waiting for checks to finish
2025/07/22 05:05:53 DEBUG : Local file system at /tmp/045205be/initdir/test_rmdirs-yeravil6: Waiting for transfers to finish
2025/07/22 05:05:53 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/07/22 05:05:53 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/07/22 05:05:53 INFO  : file1.copy2.txt: Copied (new)
2025/07/22 05:05:53 INFO  : file1.copy3.txt: Copied (new)
2025/07/22 05:05:53 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/07/22 05:05:53 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 INFO  : file1.copy4.txt: Copied (new)
2025/07/22 05:05:53 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:05:53 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/07/22 05:05:53 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/07/22 05:05:53 INFO  : file1.copy5.txt: Copied (new)
2025/07/22 05:05:53 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 05:05:53 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 05:05:53 INFO  : file1.txt: Copied (new)
2025/07/22 05:05:53 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 05:05:53 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 05:05:53 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:05:53 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:05:53 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/07/22 05:05:53 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_rmdirs-pexovim9"
2025/07/22 05:05:53 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:53 DEBUG : Local file system at /tmp/045205be/datadir/test_rmdirs-pexovim9: Waiting for checks to finish
2025/07/22 05:05:53 DEBUG : Local file system at /tmp/045205be/datadir/test_rmdirs-pexovim9: Waiting for transfers to finish
2025/07/22 05:05:53 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:05:53 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/07/22 05:05:53 INFO  : file1.txt: Copied (new)
2025/07/22 05:05:54 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_rmdirs-yeravil6
2025/07/22 05:05:54 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:54 DEBUG : subdir: Making directory with metadata
2025/07/22 05:05:56 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 05:05:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 05:05:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1': Waiting for checks to finish
2025/07/22 05:05:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1': Waiting for transfers to finish
2025/07/22 05:05:57 DEBUG : file1.copy2.txt: md5 = d6691d999b7ebeebbfa671ef4cc66bc4 OK
2025/07/22 05:05:57 INFO  : file1.copy2.txt: Copied (new)
2025/07/22 05:05:57 DEBUG : file1.copy3.txt: md5 = 5dc1141506f99c59cb03edfa63e7ab79 OK
2025/07/22 05:05:57 INFO  : file1.copy3.txt: Copied (new)
2025/07/22 05:05:57 DEBUG : RCLONE_TEST: md5 = 6cf4a007a55548698a2a47cd2016b3af OK
2025/07/22 05:05:57 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:05:57 DEBUG : file1.copy1.txt: md5 = be11866fee12bd2dd68682bf3abf7d33 OK
2025/07/22 05:05:57 INFO  : file1.copy1.txt: Copied (new)
2025/07/22 05:05:58 DEBUG : file1.copy5.txt: md5 = 6cf5ed0187997bca18554ae7e2502eb3 OK
2025/07/22 05:05:58 INFO  : file1.copy5.txt: Copied (new)
2025/07/22 05:05:58 DEBUG : file1.copy4.txt: md5 = 5999bb3d718df2c509810a6a930d206d OK
2025/07/22 05:05:58 INFO  : file1.copy4.txt: Copied (new)
2025/07/22 05:05:58 DEBUG : subdir/file20.txt: md5 = 21f549007bdb036581d7f368ef948b7c OK
2025/07/22 05:05:58 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 05:05:58 DEBUG : file1.txt: md5 = db3ca8ed6f7ffba8c6e69762034a9397 OK
2025/07/22 05:05:58 INFO  : file1.txt: Copied (new)
2025/07/22 05:05:58 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1'
2025/07/22 05:06:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:02 DEBUG : subdir: Making directory with metadata
2025/07/22 05:06:03 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/07/22 05:06:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2': Waiting for checks to finish
2025/07/22 05:06:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2': Waiting for transfers to finish
2025/07/22 05:06:04 DEBUG : file1.copy1.txt: md5 = 34c1341dff5e7da79f2a1fc95b925a9b OK
2025/07/22 05:06:04 INFO  : file1.copy1.txt: Copied (new)
2025/07/22 05:06:04 DEBUG : file1.copy3.txt: md5 = f5e0256f5a675e49cc4ab3dce03bd9f8 OK
2025/07/22 05:06:04 INFO  : file1.copy3.txt: Copied (new)
2025/07/22 05:06:04 DEBUG : file1.copy2.txt: md5 = 95fe9974e85b724516e936c60b193e9f OK
2025/07/22 05:06:04 INFO  : file1.copy2.txt: Copied (new)
2025/07/22 05:06:04 DEBUG : RCLONE_TEST: md5 = be1a3eccf07efe451151ec97049e03db OK
2025/07/22 05:06:04 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:06:05 DEBUG : file1.copy5.txt: md5 = 3477a0f559f93639942c9af2ef0045c5 OK
2025/07/22 05:06:05 INFO  : file1.copy5.txt: Copied (new)
2025/07/22 05:06:05 DEBUG : file1.txt: md5 = afc42a4fd0b97ab2f810ca1be2927bd5 OK
2025/07/22 05:06:05 INFO  : file1.txt: Copied (new)
2025/07/22 05:06:05 DEBUG : file1.copy4.txt: md5 = 28d1393e4e7721c175ecae726a5009eb OK
2025/07/22 05:06:05 INFO  : file1.copy4.txt: Copied (new)
2025/07/22 05:06:05 DEBUG : subdir/file20.txt: md5 = 0ccf0d7d75e3aa4300be4cc75ff803ad OK
2025/07/22 05:06:05 INFO  : subdir/file20.txt: Copied (new)
2025/07/22 05:06:05 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2'
2025/07/22 05:06:06 NOTICE: (01)  : test rmdirs
2025/07/22 05:06:06 NOTICE: (02)  : test initial bisync
2025/07/22 05:06:06 NOTICE: (03)  : bisync resync
2025/07/22 05:06:11 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:12 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:12 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:06:12 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:06:12 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:12 INFO  : Copying Path2 files to Path1
2025/07/22 05:06:12 INFO  : - Path2    Resync is copying files to         - Path1
2025/07/22 05:06:13 INFO  : - Path1    Resync is copying files to         - Path2
2025/07/22 05:06:13 INFO  : Resync updating listings
2025/07/22 05:06:13 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:13 INFO  : Bisync successful
2025/07/22 05:06:13 NOTICE: (04)  : test 1. delete path1 subdir file
2025/07/22 05:06:13 NOTICE: (05)  : delete-file TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/subdir/file20.txt
2025/07/22 05:06:15 INFO  : file20.txt: Deleted
2025/07/22 05:06:15 NOTICE: (06)  : test 2. run bisync without remove-empty-dirs
2025/07/22 05:06:15 NOTICE: (07)  : bisync
2025/07/22 05:06:21 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:22 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:06:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:06:22 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:22 INFO  : Building Path1 and Path2 listings
2025/07/22 05:06:23 INFO  : Path1 checking for diffs
2025/07/22 05:06:23 INFO  : - Path1    File was deleted          - subdir/file20.txt
2025/07/22 05:06:23 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/07/22 05:06:23 INFO  : Path2 checking for diffs
2025/07/22 05:06:23 INFO  : Applying changes
2025/07/22 05:06:23 INFO  : - Path2    Queue delete              - TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/subdir/file20.txt
2025/07/22 05:06:23 INFO  : - Path1    Do queued copies to                - Path2
2025/07/22 05:06:24 INFO  : subdir/file20.txt: Deleted
2025/07/22 05:06:24 INFO  : Updating listings
2025/07/22 05:06:24 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:24 INFO  : Bisync successful
2025/07/22 05:06:24 NOTICE: (08)  : test 3. confirm the subdir still exists on both paths
2025/07/22 05:06:24 NOTICE: (09)  : list-dirs TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/
2025/07/22 05:06:25 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/07/22 05:06:25 NOTICE: (10)  : list-dirs TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/
2025/07/22 05:06:25 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/07/22 05:06:25 NOTICE: (11)  : test 4. run bisync with remove-empty-dirs
2025/07/22 05:06:25 NOTICE: (12)  : bisync remove-empty-dirs
2025/07/22 05:06:31 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:32 INFO  : subdir: Set directory modification time (using SetModTime)
2025/07/22 05:06:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/07/22 05:06:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/07/22 05:06:32 INFO  : Synching Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:32 INFO  : Building Path1 and Path2 listings
2025/07/22 05:06:32 INFO  : Path1 checking for diffs
2025/07/22 05:06:32 INFO  : Path2 checking for diffs
2025/07/22 05:06:32 INFO  : No changes found
2025/07/22 05:06:32 INFO  : Updating listings
2025/07/22 05:06:32 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/"
2025/07/22 05:06:32 INFO  : Removing empty directories
2025/07/22 05:06:33 INFO  : subdir: Removing directory
2025/07/22 05:06:34 INFO  : subdir: Removing directory
2025/07/22 05:06:35 INFO  : Bisync successful
2025/07/22 05:06:35 NOTICE: (13)  : test 5. confirm the subdir has been removed on both paths
2025/07/22 05:06:35 NOTICE: (14)  : list-dirs TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path1/
2025/07/22 05:06:35 NOTICE: (15)  : list-dirs TestCryptDrive:rclone-test-civitaf8qefe/045205be/rmdirs/path2/
    bisync_test.go:560: TEST rmdirs PASSED
=== RUN   TestBisyncRemoteRemote/volatile
2025/07/22 05:06:36 INFO  : path1: Making directory
2025/07/22 05:06:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path1"
2025/07/22 05:06:36 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/diudgf4526ccv228o9fs0ilue0/fb9eja2dfq85i3304q4elu1emo"
2025/07/22 05:06:38 INFO  : path2: Making directory
2025/07/22 05:06:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path2"
2025/07/22 05:06:38 DEBUG : Creating backend with remote "TestDrive:crypt/9u1sdqnql8bc1140m40sdjug4a09b00uvmlkp9mr2df412esu3vg/o349n3sdkmlavjslj6irdd2g4c/diudgf4526ccv228o9fs0ilue0/b53o4pennvpoq8r59fti0dnbl8"
2025/07/22 05:06:40 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/07/22 05:06:40 DEBUG : Creating backend with remote "/tmp/045205be/initdir/test_volatile-repilez9"
2025/07/22 05:06:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : Local file system at /tmp/045205be/initdir/test_volatile-repilez9: Waiting for checks to finish
2025/07/22 05:06:40 DEBUG : Local file system at /tmp/045205be/initdir/test_volatile-repilez9: Waiting for transfers to finish
2025/07/22 05:06:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/07/22 05:06:40 INFO  : file1.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/07/22 05:06:40 INFO  : file2.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/07/22 05:06:40 INFO  : file3.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/07/22 05:06:40 INFO  : file4.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/07/22 05:06:40 INFO  : file6.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/07/22 05:06:40 INFO  : file5.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/07/22 05:06:40 INFO  : file7.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/07/22 05:06:40 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/07/22 05:06:40 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:06:40 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/07/22 05:06:40 INFO  : file8.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/07/22 05:06:40 DEBUG : Creating backend with remote "/tmp/045205be/datadir/test_volatile-pogimun4"
2025/07/22 05:06:40 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:40 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/07/22 05:06:40 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/07/22 05:06:40 INFO  : dummy.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : Local file system at /tmp/045205be/datadir/test_volatile-pogimun4: Waiting for checks to finish
2025/07/22 05:06:40 DEBUG : Local file system at /tmp/045205be/datadir/test_volatile-pogimun4: Waiting for transfers to finish
2025/07/22 05:06:40 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:06:40 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/07/22 05:06:40 INFO  : file10.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:06:40 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/07/22 05:06:40 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:06:40 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/07/22 05:06:40 INFO  : file11.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/07/22 05:06:40 INFO  : file2.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/07/22 05:06:40 INFO  : file1.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/07/22 05:06:40 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/07/22 05:06:40 INFO  : file5L.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/07/22 05:06:40 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/07/22 05:06:40 INFO  : file5R.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:06:40 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/07/22 05:06:40 INFO  : file6.txt: Copied (new)
2025/07/22 05:06:40 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/07/22 05:06:40 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/07/22 05:06:40 INFO  : file7.txt: Copied (new)
2025/07/22 05:06:41 NOTICE: checking initFs Local file system at /tmp/045205be/initdir/test_volatile-repilez9
2025/07/22 05:06:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path1': Waiting for checks to finish
2025/07/22 05:06:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path1': Waiting for transfers to finish
2025/07/22 05:06:44 DEBUG : RCLONE_TEST: md5 = 5cd9b4cdc780a1185964b79188d2d10c OK
2025/07/22 05:06:44 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:06:44 DEBUG : file2.txt: md5 = 16532d1be74ba3fda55e476711def9f4 OK
2025/07/22 05:06:44 INFO  : file2.txt: Copied (new)
2025/07/22 05:06:45 DEBUG : file3.txt: md5 = a60fd2d239ee5a1dd5d65c6f51479d51 OK
2025/07/22 05:06:45 INFO  : file3.txt: Copied (new)
2025/07/22 05:06:45 DEBUG : file1.txt: md5 = 45a1ae91383d26539acd71e67549ab70 OK
2025/07/22 05:06:45 INFO  : file1.txt: Copied (new)
2025/07/22 05:06:46 DEBUG : file4.txt: md5 = c8523869a2bf82798c89d1688e41d974 OK
2025/07/22 05:06:46 INFO  : file4.txt: Copied (new)
2025/07/22 05:06:46 DEBUG : file5.txt: md5 = f2189b8be0541924d3e7581f4b947334 OK
2025/07/22 05:06:46 INFO  : file5.txt: Copied (new)
2025/07/22 05:06:46 DEBUG : file7.txt: md5 = a13a2787e64bdd1f60e00ac25d34fc06 OK
2025/07/22 05:06:46 INFO  : file7.txt: Copied (new)
2025/07/22 05:06:46 DEBUG : file6.txt: md5 = 007499d60768034752990cdbbf5b618d OK
2025/07/22 05:06:46 INFO  : file6.txt: Copied (new)
2025/07/22 05:06:47 DEBUG : file8.txt: md5 = f7421091c146ef4fe32b4eb7d9eef7e4 OK
2025/07/22 05:06:47 INFO  : file8.txt: Copied (new)
2025/07/22 05:06:47 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path1'
2025/07/22 05:06:48 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/07/22 05:06:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path2': Waiting for checks to finish
2025/07/22 05:06:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path2': Waiting for transfers to finish
2025/07/22 05:06:50 DEBUG : RCLONE_TEST: md5 = c86395805acd13bfa7802676f4ffb7aa OK
2025/07/22 05:06:50 INFO  : RCLONE_TEST: Copied (new)
2025/07/22 05:06:50 DEBUG : file2.txt: md5 = 0e272c2496e02b4bc0d15e21d00eb44e OK
2025/07/22 05:06:50 INFO  : file2.txt: Copied (new)
2025/07/22 05:06:50 DEBUG : file1.txt: md5 = 272194b3d307dedafa9dc6c3bfe17bfb OK
2025/07/22 05:06:50 INFO  : file1.txt: Copied (new)
2025/07/22 05:06:50 DEBUG : file3.txt: md5 = 615ed8c4737120be435e9c2e706d5abe OK
2025/07/22 05:06:50 INFO  : file3.txt: Copied (new)
2025/07/22 05:06:51 DEBUG : file4.txt: md5 = fdfb786edd0f2f44c5980e5a1a54fa63 OK
2025/07/22 05:06:51 INFO  : file4.txt: Copied (new)
2025/07/22 05:06:51 DEBUG : file5.txt: md5 = 334bcaf2f2276825978101b03fca4f90 OK
2025/07/22 05:06:51 INFO  : file5.txt: Copied (new)
2025/07/22 05:06:51 DEBUG : file6.txt: md5 = 1f40d9d2cff11a86583cb0b073817cd4 OK
2025/07/22 05:06:51 INFO  : file6.txt: Copied (new)
2025/07/22 05:06:51 DEBUG : file7.txt: md5 = 1ca6141d1c84ab2e6aea087f9537eac2 OK
2025/07/22 05:06:51 INFO  : file7.txt: Copied (new)
2025/07/22 05:06:52 DEBUG : file8.txt: md5 = c5275c12f1247eb488803645b6299fdb OK
2025/07/22 05:06:52 INFO  : file8.txt: Copied (new)
2025/07/22 05:06:52 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe/045205be/volatile/path2'
2025/07/22 05:06:53 NOTICE: (01)  : test volatile
2025/07/22 05:06:53 NOTICE: (02)  : test initial bisync
2025/07/22 05:06:53 NOTICE: (03)  : bisync resync
    bisync_test.go:937: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/07/22 05:06:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-civitaf8qefe': Purge remote
--- PASS: TestBisyncRemoteRemote (889.05s)
    --- PASS: TestBisyncRemoteRemote/max_delete_path2_force (61.47s)
    --- PASS: TestBisyncRemoteRemote/nomodtime (111.91s)
    --- PASS: TestBisyncRemoteRemote/normalization (195.11s)
    --- PASS: TestBisyncRemoteRemote/rclone_args (182.28s)
    --- PASS: TestBisyncRemoteRemote/resolve (110.17s)
    --- PASS: TestBisyncRemoteRemote/resync (72.01s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (90.44s)
    --- PASS: TestBisyncRemoteRemote/rmdirs (47.28s)
    --- SKIP: TestBisyncRemoteRemote/volatile (16.54s)
PASS
2025/07/22 05:06:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jeleguv2vuyo': Purge remote
2025/07/22 05:06:54 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestBisyncRemoteRemote$/^(max_delete_path2_force|nomodtime|normalization|rclone_args|resolve|resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 14m50.624231774s (try 2/5)