"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 3/5)
2024/11/14 22:20:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sokover2kiwo"
2024/11/14 22:20:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/14 22:20:59 DEBUG : Creating backend with remote "TestDrive:crypt/clbtgchah7r4iimve72ffvk17rul8o6jhdlsgg4r9c8439g1m1mg"
2024/11/14 22:21:00 DEBUG : Creating backend with remote "/tmp/rclone1780603754"
=== RUN   TestBisyncRemoteRemote
2024/11/14 22:21:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu"
2024/11/14 22:21:00 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60"
2024/11/14 22:21:05 NOTICE: remote: TestCryptDrive:rclone-test-canocun7vitu
=== RUN   TestBisyncRemoteRemote/resync
2024/11/14 22:21:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta"
2024/11/14 22:21:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/14 22:21:05 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84"
2024/11/14 22:21:06 DEBUG : path1: Making directory
2024/11/14 22:21:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1"
2024/11/14 22:21:14 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/dfoecmi82rcood27dsu6dlrcss/fb9eja2dfq85i3304q4elu1emo"
2024/11/14 22:21:22 DEBUG : path2: Making directory
2024/11/14 22:21:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2"
2024/11/14 22:21:23 DEBUG : Config file has changed externally - reloading
2024/11/14 22:21:23 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/dfoecmi82rcood27dsu6dlrcss/b53o4pennvpoq8r59fti0dnbl8"
2024/11/14 22:21:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2024/11/14 22:21:29 DEBUG : Creating backend with remote "/tmp/222105ta/initdir/test_resync-xemamun9"
2024/11/14 22:21:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : Local file system at /tmp/222105ta/initdir/test_resync-xemamun9: Waiting for checks to finish
2024/11/14 22:21:29 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2024/11/14 22:21:29 INFO  : file2.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : Local file system at /tmp/222105ta/initdir/test_resync-xemamun9: Waiting for transfers to finish
2024/11/14 22:21:29 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2024/11/14 22:21:29 INFO  : file3.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/11/14 22:21:29 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2024/11/14 22:21:29 INFO  : file4.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2024/11/14 22:21:29 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:21:29 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2024/11/14 22:21:29 INFO  : file7.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2024/11/14 22:21:29 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2024/11/14 22:21:29 INFO  : file5.txt: Copied (new)
2024/11/14 22:21:29 INFO  : file6.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:21:29 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2024/11/14 22:21:29 INFO  : file1.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2024/11/14 22:21:29 DEBUG : Creating backend with remote "/tmp/222105ta/datadir/test_resync-kigigom3"
2024/11/14 22:21:29 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:29 DEBUG : Local file system at /tmp/222105ta/datadir/test_resync-kigigom3: Waiting for checks to finish
2024/11/14 22:21:29 DEBUG : Local file system at /tmp/222105ta/datadir/test_resync-kigigom3: Waiting for transfers to finish
2024/11/14 22:21:29 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:21:29 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt
2024/11/14 22:21:29 INFO  : fileA.txt: Copied (new)
2024/11/14 22:21:29 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:21:29 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt
2024/11/14 22:21:29 INFO  : fileB.txt: Copied (new)
2024/11/14 22:21:31 NOTICE: checking initFs Local file system at /tmp/222105ta/initdir/test_resync-xemamun9
2024/11/14 22:21:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1': Waiting for checks to finish
2024/11/14 22:21:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1': Waiting for transfers to finish
2024/11/14 22:21:38 DEBUG : RCLONE_TEST: md5 = e5d0a1e288110a17c5d8c39f5a05a3e0 OK
2024/11/14 22:21:38 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:21:41 DEBUG : file2.txt: md5 = 62e381a6f3cefac4cd808cb97c8f90ba OK
2024/11/14 22:21:41 INFO  : file2.txt: Copied (new)
2024/11/14 22:21:42 DEBUG : file1.txt: md5 = c51af1fff5a0d418cbe69d53991b56e2 OK
2024/11/14 22:21:42 INFO  : file1.txt: Copied (new)
2024/11/14 22:21:42 DEBUG : file3.txt: md5 = a8c7254f097fcb47e555e3db08981957 OK
2024/11/14 22:21:42 INFO  : file3.txt: Copied (new)
2024/11/14 22:21:43 DEBUG : file5.txt: md5 = 33f9bac9bdb917346cd7ddff18d6e559 OK
2024/11/14 22:21:43 INFO  : file5.txt: Copied (new)
2024/11/14 22:21:44 DEBUG : file6.txt: md5 = 2a8424962403485cb2e96bdcfed77b62 OK
2024/11/14 22:21:44 INFO  : file6.txt: Copied (new)
2024/11/14 22:21:44 DEBUG : file4.txt: md5 = 9756b84e208b53a84bb3b983b4e612f3 OK
2024/11/14 22:21:44 INFO  : file4.txt: Copied (new)
2024/11/14 22:21:44 DEBUG : file7.txt: md5 = 6d0d1cc7a66bbb6fb6e1c63588378603 OK
2024/11/14 22:21:44 INFO  : file7.txt: Copied (new)
2024/11/14 22:21:44 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1'
2024/11/14 22:21:45 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:21:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2': Waiting for checks to finish
2024/11/14 22:21:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2': Waiting for transfers to finish
2024/11/14 22:21:48 DEBUG : file2.txt: md5 = ac0440aab6154b356442b636c5bb9a07 OK
2024/11/14 22:21:48 INFO  : file2.txt: Copied (new)
2024/11/14 22:21:48 DEBUG : RCLONE_TEST: md5 = 7664d6059a16d77597995258346976af OK
2024/11/14 22:21:48 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:21:49 DEBUG : file1.txt: md5 = 4e12c82cdd0070ba0a9af693e99aac73 OK
2024/11/14 22:21:49 INFO  : file1.txt: Copied (new)
2024/11/14 22:21:49 DEBUG : file3.txt: md5 = 2a939af0d22877dc79df554c97ae013d OK
2024/11/14 22:21:49 INFO  : file3.txt: Copied (new)
2024/11/14 22:21:50 DEBUG : file4.txt: md5 = 2e8e6cffdc18fe5ba777ef94be65486d OK
2024/11/14 22:21:50 INFO  : file4.txt: Copied (new)
2024/11/14 22:21:50 DEBUG : file5.txt: md5 = 76ed01b82a2d5c88173299c9c8067021 OK
2024/11/14 22:21:50 INFO  : file5.txt: Copied (new)
2024/11/14 22:21:51 DEBUG : file7.txt: md5 = 872b5968555a42000c412de9fd56307d OK
2024/11/14 22:21:51 INFO  : file7.txt: Copied (new)
2024/11/14 22:21:51 DEBUG : file6.txt: md5 = 42638601a6696fc477d5fc4364d2bbf2 OK
2024/11/14 22:21:51 INFO  : file6.txt: Copied (new)
2024/11/14 22:21:51 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2'
2024/11/14 22:21:51 NOTICE: (01)  : test resync
2024/11/14 22:21:51 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2024/11/14 22:21:51 NOTICE: (03)  : purge-children TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/
2024/11/14 22:21:58 NOTICE: (04)  : bisync resync
2024/11/14 22:22:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:22:05 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:22:05 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:05 INFO  : Copying Path2 files to Path1
2024/11/14 22:22:05 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:22:07 INFO  : file3.txt: Copied (server-side copy)
2024/11/14 22:22:07 INFO  : RCLONE_TEST: Copied (server-side copy)
2024/11/14 22:22:07 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:22:07 INFO  : file2.txt: Copied (server-side copy)
2024/11/14 22:22:08 INFO  : file6.txt: Copied (server-side copy)
2024/11/14 22:22:08 INFO  : file7.txt: Copied (server-side copy)
2024/11/14 22:22:08 INFO  : file5.txt: Copied (server-side copy)
2024/11/14 22:22:09 INFO  : file4.txt: Copied (server-side copy)
2024/11/14 22:22:09 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:22:09 INFO  : Resync updating listings
2024/11/14 22:22:09 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:09 INFO  : Bisync successful
2024/11/14 22:22:09 NOTICE: (05)  : move-listings empty-path1
2024/11/14 22:22:09 NOTICE: (06)  : test 2. resync with empty path2, resulting in synching all content to path2.
2024/11/14 22:22:09 NOTICE: (07)  : purge-children TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/
2024/11/14 22:22:15 NOTICE: (08)  : bisync resync
2024/11/14 22:22:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:22:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:22:22 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:22 INFO  : Copying Path2 files to Path1
2024/11/14 22:22:22 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:22:22 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:22:24 INFO  : RCLONE_TEST: Copied (server-side copy)
2024/11/14 22:22:24 INFO  : file3.txt: Copied (server-side copy)
2024/11/14 22:22:24 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:22:24 INFO  : file2.txt: Copied (server-side copy)
2024/11/14 22:22:27 INFO  : file5.txt: Copied (server-side copy)
2024/11/14 22:22:27 INFO  : file7.txt: Copied (server-side copy)
2024/11/14 22:22:27 INFO  : file4.txt: Copied (server-side copy)
2024/11/14 22:22:27 INFO  : file6.txt: Copied (server-side copy)
2024/11/14 22:22:27 INFO  : Resync updating listings
2024/11/14 22:22:27 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:27 INFO  : Bisync successful
2024/11/14 22:22:27 NOTICE: (09)  : move-listings empty-path2
2024/11/14 22:22:27 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2024/11/14 22:22:27 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/222105ta/datadir/test_resync-kigigom3/ fileA.txt
2024/11/14 22:22:27 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/222105ta/datadir/test_resync-kigigom3/ fileB.txt
2024/11/14 22:22:27 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2024/11/14 22:22:27 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2024/11/14 22:22:27 NOTICE: (15)  : delete-file TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/file1.txt
2024/11/14 22:22:28 INFO  : file1.txt: Deleted
2024/11/14 22:22:28 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2024/11/14 22:22:28 NOTICE: (17)  : delete-file TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/file2.txt
2024/11/14 22:22:29 INFO  : file2.txt: Deleted
2024/11/14 22:22:29 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2024/11/14 22:22:29 NOTICE: (19)  : copy-as /tmp/222105ta/datadir/test_resync-kigigom3/fileA.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/ file3.txt
2024/11/14 22:22:31 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2024/11/14 22:22:31 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2024/11/14 22:22:31 NOTICE: (21)  : delete-file TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/file4.txt
2024/11/14 22:22:32 INFO  : file4.txt: Deleted
2024/11/14 22:22:32 NOTICE: (22)  : copy-as /tmp/222105ta/datadir/test_resync-kigigom3/fileA.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/ file4.txt
2024/11/14 22:22:33 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2024/11/14 22:22:33 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2024/11/14 22:22:33 NOTICE: (24)  : copy-as /tmp/222105ta/datadir/test_resync-kigigom3/fileB.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/ file5.txt
2024/11/14 22:22:35 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2024/11/14 22:22:35 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2024/11/14 22:22:35 NOTICE: (26)  : copy-as /tmp/222105ta/datadir/test_resync-kigigom3/fileB.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/ file6.txt
2024/11/14 22:22:37 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2024/11/14 22:22:37 NOTICE: (27)  : copy-as /tmp/222105ta/datadir/test_resync-kigigom3/fileA.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/ file6.txt
2024/11/14 22:22:41 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2024/11/14 22:22:41 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2024/11/14 22:22:41 NOTICE: (29)  : test run bisync with resync
2024/11/14 22:22:41 NOTICE: (30)  : bisync resync
2024/11/14 22:22:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:22:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:22:50 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:50 INFO  : Copying Path2 files to Path1
2024/11/14 22:22:50 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:22:52 INFO  : file2.txt: Copied (server-side copy)
2024/11/14 22:22:52 INFO  : file4.txt: Copied (server-side copy)
2024/11/14 22:22:52 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:22:54 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:22:54 INFO  : file3.txt: Copied (server-side copy)
2024/11/14 22:22:54 INFO  : file5.txt: Copied (server-side copy)
2024/11/14 22:22:54 INFO  : file6.txt: Copied (server-side copy)
2024/11/14 22:22:54 INFO  : Resync updating listings
2024/11/14 22:22:54 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:22:54 INFO  : Bisync successful
2024/11/14 22:22:54 NOTICE: (31)  : copy-listings mixed-diffs
2024/11/14 22:22:54 NOTICE: (32)  : test run normal bisync
2024/11/14 22:22:54 NOTICE: (33)  : bisync
2024/11/14 22:23:01 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:23:01 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:23:01 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:23:01 INFO  : Building Path1 and Path2 listings
2024/11/14 22:23:02 INFO  : Path1 checking for diffs
2024/11/14 22:23:02 INFO  : Path2 checking for diffs
2024/11/14 22:23:02 INFO  : No changes found
2024/11/14 22:23:02 INFO  : Updating listings
2024/11/14 22:23:02 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:23:02 INFO  : Bisync successful
2024/11/14 22:23:02 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2024/11/14 22:23:02 NOTICE: (35)  : purge-children TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/
2024/11/14 22:23:07 NOTICE: (36)  : bisync
2024/11/14 22:23:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:23:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:23:14 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync/path2/"
2024/11/14 22:23:14 INFO  : Building Path1 and Path2 listings
2024/11/14 22:23:14 INFO  : Path1 checking for diffs
2024/11/14 22:23:14 INFO  : Path2 checking for diffs
2024/11/14 22:23:14 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/222105ta/workdir/TestCryptDrive_rclone-test-canocun7vitu_222105ta_resync_path1..TestCryptDrive_rclone-test-canocun7vitu_222105ta_resync_path2.path2.lst-new
2024/11/14 22:23:14 ERROR : Bisync critical error: empty current Path2 listing: /tmp/222105ta/workdir/TestCryptDrive_rclone-test-canocun7vitu_222105ta_resync_path1..TestCryptDrive_rclone-test-canocun7vitu_222105ta_resync_path2.path2.lst-new
2024/11/14 22:23:14 ERROR : Bisync aborted. Must run --resync to recover.
2024/11/14 22:23:14 NOTICE: Bisync error: bisync aborted
    bisync_test.go:556: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2024/11/14 22:23:16 DEBUG : path1: Making directory
2024/11/14 22:23:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1"
2024/11/14 22:23:16 DEBUG : Config file has changed externally - reloading
2024/11/14 22:23:16 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/p8bgtduumu3o6tfedl25miakvk/fb9eja2dfq85i3304q4elu1emo"
2024/11/14 22:23:18 DEBUG : path2: Making directory
2024/11/14 22:23:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2"
2024/11/14 22:23:18 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/p8bgtduumu3o6tfedl25miakvk/b53o4pennvpoq8r59fti0dnbl8"
2024/11/14 22:23:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2024/11/14 22:23:20 DEBUG : Creating backend with remote "/tmp/222105ta/initdir/test_resync_modes-qacisid5"
2024/11/14 22:23:20 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:23:20 DEBUG : Local file system at /tmp/222105ta/initdir/test_resync_modes-qacisid5: Waiting for checks to finish
2024/11/14 22:23:20 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2024/11/14 22:23:20 INFO  : file1.txt: Copied (new)
2024/11/14 22:23:20 DEBUG : Local file system at /tmp/222105ta/initdir/test_resync_modes-qacisid5: Waiting for transfers to finish
2024/11/14 22:23:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/11/14 22:23:20 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2024/11/14 22:23:20 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:23:20 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:23:20 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2024/11/14 22:23:20 INFO  : file2.txt: Copied (new)
2024/11/14 22:23:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2024/11/14 22:23:20 DEBUG : Creating backend with remote "/tmp/222105ta/datadir/test_resync_modes-bageyap7"
2024/11/14 22:23:20 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:20 DEBUG : Local file system at /tmp/222105ta/datadir/test_resync_modes-bageyap7: Waiting for checks to finish
2024/11/14 22:23:20 DEBUG : Local file system at /tmp/222105ta/datadir/test_resync_modes-bageyap7: Waiting for transfers to finish
2024/11/14 22:23:20 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2024/11/14 22:23:20 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2024/11/14 22:23:20 INFO  : file2.txt: Copied (new)
2024/11/14 22:23:20 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2024/11/14 22:23:20 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2024/11/14 22:23:20 INFO  : file1L.txt: Copied (new)
2024/11/14 22:23:20 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2024/11/14 22:23:20 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2024/11/14 22:23:20 INFO  : file1R.txt: Copied (new)
2024/11/14 22:23:21 NOTICE: checking initFs Local file system at /tmp/222105ta/initdir/test_resync_modes-qacisid5
2024/11/14 22:23:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:23:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1': Waiting for checks to finish
2024/11/14 22:23:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1': Waiting for transfers to finish
2024/11/14 22:23:25 DEBUG : RCLONE_TEST: md5 = bf4bb75764bebd89ff9b9b564d256d4a OK
2024/11/14 22:23:25 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:23:26 DEBUG : file2.txt: md5 = 0482515d8ef1be9f2b2000d132907be7 OK
2024/11/14 22:23:26 INFO  : file2.txt: Copied (new)
2024/11/14 22:23:26 DEBUG : file1.txt: md5 = f41c4610584c1f96b65faac200d6b574 OK
2024/11/14 22:23:26 INFO  : file1.txt: Copied (new)
2024/11/14 22:23:26 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1'
2024/11/14 22:23:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:23:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:27 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:23:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2': Waiting for checks to finish
2024/11/14 22:23:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2': Waiting for transfers to finish
2024/11/14 22:23:30 DEBUG : RCLONE_TEST: md5 = 41220c7eed0109a8cc5dc0821beade87 OK
2024/11/14 22:23:30 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:23:30 DEBUG : file1.txt: md5 = 56786bec998a21acd6ed7753c0e6c15c OK
2024/11/14 22:23:30 INFO  : file1.txt: Copied (new)
2024/11/14 22:23:30 DEBUG : file2.txt: md5 = e2d5ec438de8c87b27d17f8d67ac14cf OK
2024/11/14 22:23:30 INFO  : file2.txt: Copied (new)
2024/11/14 22:23:30 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2'
2024/11/14 22:23:31 NOTICE: (01)  : test resync-mode
2024/11/14 22:23:31 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:23:31 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:23:31 NOTICE: (04)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:23:33 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:23:33 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:23:33 NOTICE: (06)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:23:35 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:23:35 NOTICE: (07)  : test bisync run with --resync-mode=newer
2024/11/14 22:23:35 NOTICE: (08)  : bisync resync resync-mode=newer
2024/11/14 22:23:42 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:23:42 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:23:42 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:23:42 INFO  : Copying Path2 files to Path1
2024/11/14 22:23:42 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:23:42 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:23:44 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:23:44 INFO  : Resync updating listings
2024/11/14 22:23:44 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:23:44 INFO  : Bisync successful
2024/11/14 22:23:44 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:23:44 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:23:44 NOTICE: (11)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:23:46 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:23:46 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:23:46 NOTICE: (13)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:23:48 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:23:48 NOTICE: (14)  : test bisync run with --resync-mode=path2
2024/11/14 22:23:48 NOTICE: (15)  : bisync resync resync-mode=path2
2024/11/14 22:23:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:23:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:23:55 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:23:55 INFO  : Copying Path2 files to Path1
2024/11/14 22:23:55 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:23:58 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:23:58 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:23:58 INFO  : Resync updating listings
2024/11/14 22:23:58 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:23:58 INFO  : Bisync successful
2024/11/14 22:23:58 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:23:58 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:23:58 NOTICE: (18)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:24:00 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:00 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:24:00 NOTICE: (20)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:24:02 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:02 NOTICE: (21)  : test bisync run with --resync-mode=larger
2024/11/14 22:24:02 NOTICE: (22)  : bisync resync resync-mode=larger
2024/11/14 22:24:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:24:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:24:09 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:09 INFO  : Copying Path2 files to Path1
2024/11/14 22:24:09 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:24:09 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2024/11/14 22:24:11 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:24:11 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:24:12 INFO  : Resync updating listings
2024/11/14 22:24:12 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:12 INFO  : Bisync successful
2024/11/14 22:24:12 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:24:12 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:24:12 NOTICE: (25)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:24:13 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:13 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:24:13 NOTICE: (27)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:24:15 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:15 NOTICE: (28)  : test bisync run with --resync-mode=older
2024/11/14 22:24:15 NOTICE: (29)  : bisync resync resync-mode=older
2024/11/14 22:24:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:24:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:24:22 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:22 INFO  : Copying Path2 files to Path1
2024/11/14 22:24:22 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:24:22 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
2024/11/14 22:24:25 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:24:25 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:24:25 INFO  : Resync updating listings
2024/11/14 22:24:25 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:25 INFO  : Bisync successful
2024/11/14 22:24:25 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:24:25 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:24:25 NOTICE: (32)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:24:27 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:27 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:24:27 NOTICE: (34)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:24:29 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:29 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2024/11/14 22:24:29 NOTICE: (36)  : bisync resync resync-mode=smaller
2024/11/14 22:24:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:24:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:24:36 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:36 INFO  : Copying Path2 files to Path1
2024/11/14 22:24:36 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:24:36 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2024/11/14 22:24:36 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:24:36 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2024/11/14 22:24:38 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:24:38 INFO  : Resync updating listings
2024/11/14 22:24:38 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:38 INFO  : Bisync successful
2024/11/14 22:24:38 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2024/11/14 22:24:38 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:24:38 NOTICE: (39)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:24:40 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:40 NOTICE: (40)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file2.txt
2024/11/14 22:24:42 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2024/11/14 22:24:42 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:24:42 NOTICE: (42)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:24:44 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:44 NOTICE: (43)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file2.txt
2024/11/14 22:24:46 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2024/11/14 22:24:46 NOTICE: (44)  : test bisync run with --resync-mode=path1
2024/11/14 22:24:46 NOTICE: (45)  : bisync resync resync-mode=path1
2024/11/14 22:24:53 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:24:53 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:24:53 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:53 INFO  : Copying Path2 files to Path1
2024/11/14 22:24:53 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:24:53 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:24:56 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:24:56 INFO  : file2.txt: Copied (server-side copy)
2024/11/14 22:24:56 INFO  : Resync updating listings
2024/11/14 22:24:56 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:24:56 INFO  : Bisync successful
2024/11/14 22:24:56 NOTICE: (46)  : test no winner
2024/11/14 22:24:56 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1R.txt
2024/11/14 22:24:56 NOTICE: (48)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file1.txt
2024/11/14 22:24:58 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:24:58 NOTICE: (49)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1R.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file2.txt
2024/11/14 22:25:00 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2024/11/14 22:25:00 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/222105ta/datadir/test_resync_modes-bageyap7/ file1L.txt
2024/11/14 22:25:00 NOTICE: (51)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/ file1.txt
2024/11/14 22:25:01 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2024/11/14 22:25:01 NOTICE: (52)  : copy-as /tmp/222105ta/datadir/test_resync_modes-bageyap7/file1L.txt TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/ file2.txt
2024/11/14 22:25:03 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2024/11/14 22:25:03 NOTICE: (53)  : test bisync run with --resync-mode=newer
2024/11/14 22:25:03 NOTICE: (54)  : bisync resync resync-mode=newer
2024/11/14 22:25:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:25:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:25:11 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:25:11 INFO  : Copying Path2 files to Path1
2024/11/14 22:25:11 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:25:13 INFO  : file1.txt: Copied (server-side copy)
2024/11/14 22:25:13 INFO  : file2.txt: Copied (server-side copy)
2024/11/14 22:25:13 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:25:14 INFO  : Resync updating listings
2024/11/14 22:25:14 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/resync_modes/path2/"
2024/11/14 22:25:14 INFO  : Bisync successful
    bisync_test.go:556: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2024/11/14 22:25:15 DEBUG : path1: Making directory
2024/11/14 22:25:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1"
2024/11/14 22:25:15 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/dv7afrs03vrmjf6n0cfhi7ai6k/fb9eja2dfq85i3304q4elu1emo"
2024/11/14 22:25:17 DEBUG : path2: Making directory
2024/11/14 22:25:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2"
2024/11/14 22:25:17 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/dv7afrs03vrmjf6n0cfhi7ai6k/b53o4pennvpoq8r59fti0dnbl8"
2024/11/14 22:25:19 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2024/11/14 22:25:19 DEBUG : Creating backend with remote "/tmp/222105ta/initdir/test_rmdirs-wowimin8"
2024/11/14 22:25:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : subdir: Making directory with metadata
2024/11/14 22:25:19 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/11/14 22:25:19 DEBUG : Added delayed dir = "subdir", newDst=subdir
2024/11/14 22:25:19 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : Local file system at /tmp/222105ta/initdir/test_rmdirs-wowimin8: Waiting for checks to finish
2024/11/14 22:25:19 DEBUG : Local file system at /tmp/222105ta/initdir/test_rmdirs-wowimin8: Waiting for transfers to finish
2024/11/14 22:25:19 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2024/11/14 22:25:19 INFO  : file1.copy2.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2024/11/14 22:25:19 INFO  : file1.copy1.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2024/11/14 22:25:19 INFO  : file1.copy3.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2024/11/14 22:25:19 INFO  : file1.copy4.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2024/11/14 22:25:19 INFO  : file1.copy5.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:25:19 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2024/11/14 22:25:19 INFO  : subdir/file20.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2024/11/14 22:25:19 INFO  : file1.txt: Copied (new)
2024/11/14 22:25:19 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/11/14 22:25:19 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2024/11/14 22:25:19 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:25:19 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:25:19 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2024/11/14 22:25:19 DEBUG : Creating backend with remote "/tmp/222105ta/datadir/test_rmdirs-yexuniq1"
2024/11/14 22:25:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:19 DEBUG : Local file system at /tmp/222105ta/datadir/test_rmdirs-yexuniq1: Waiting for checks to finish
2024/11/14 22:25:19 DEBUG : Local file system at /tmp/222105ta/datadir/test_rmdirs-yexuniq1: Waiting for transfers to finish
2024/11/14 22:25:19 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:25:19 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2024/11/14 22:25:19 INFO  : file1.txt: Copied (new)
2024/11/14 22:25:20 NOTICE: checking initFs Local file system at /tmp/222105ta/initdir/test_rmdirs-wowimin8
2024/11/14 22:25:20 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:20 DEBUG : subdir: Making directory with metadata
2024/11/14 22:25:24 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/11/14 22:25:24 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1': Waiting for checks to finish
2024/11/14 22:25:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1': Waiting for transfers to finish
2024/11/14 22:25:25 DEBUG : file1.copy1.txt: md5 = 6c81b41820b5ad54b846ababe882c557 OK
2024/11/14 22:25:25 INFO  : file1.copy1.txt: Copied (new)
2024/11/14 22:25:25 DEBUG : file1.copy3.txt: md5 = 9d1ba8b2d25a3f5829806e8f3ce3f3c9 OK
2024/11/14 22:25:25 INFO  : file1.copy3.txt: Copied (new)
2024/11/14 22:25:25 DEBUG : file1.copy2.txt: md5 = dd1574c724cd5d2a256f429fa8d4904f OK
2024/11/14 22:25:25 INFO  : file1.copy2.txt: Copied (new)
2024/11/14 22:25:25 DEBUG : RCLONE_TEST: md5 = d738c9e402f70a4c4f36e13d9f76a87b OK
2024/11/14 22:25:25 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:25:27 DEBUG : file1.copy4.txt: md5 = 2ecfb6a4a3712d3b2e080f641e86cbeb OK
2024/11/14 22:25:27 INFO  : file1.copy4.txt: Copied (new)
2024/11/14 22:25:27 DEBUG : file1.copy5.txt: md5 = 54b93accc093809350660270640bc166 OK
2024/11/14 22:25:27 INFO  : file1.copy5.txt: Copied (new)
2024/11/14 22:25:27 DEBUG : subdir/file20.txt: md5 = c4604a947b3034d2813ba902709c1c92 OK
2024/11/14 22:25:27 INFO  : subdir/file20.txt: Copied (new)
2024/11/14 22:25:27 DEBUG : file1.txt: md5 = 41d589d115d6341b60ae4b2e9aa65ce6 OK
2024/11/14 22:25:27 INFO  : file1.txt: Copied (new)
2024/11/14 22:25:27 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1'
2024/11/14 22:25:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:28 DEBUG : subdir: Making directory with metadata
2024/11/14 22:25:30 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/11/14 22:25:30 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/11/14 22:25:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2': Waiting for checks to finish
2024/11/14 22:25:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2': Waiting for transfers to finish
2024/11/14 22:25:31 DEBUG : file1.copy3.txt: md5 = 6fe545491bdff3a3220456c5069e68b4 OK
2024/11/14 22:25:31 INFO  : file1.copy3.txt: Copied (new)
2024/11/14 22:25:31 DEBUG : file1.copy2.txt: md5 = a01257e26af6c33c0031747d1e782916 OK
2024/11/14 22:25:31 INFO  : file1.copy2.txt: Copied (new)
2024/11/14 22:25:31 DEBUG : file1.copy1.txt: md5 = b693287c181aac4e7619938a66c57402 OK
2024/11/14 22:25:31 INFO  : file1.copy1.txt: Copied (new)
2024/11/14 22:25:31 DEBUG : RCLONE_TEST: md5 = 815dc651d687469ecd7bb8b930935d97 OK
2024/11/14 22:25:31 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:25:33 DEBUG : file1.copy4.txt: md5 = 039ebc43bcfd3f09f411b8e6bbc252bd OK
2024/11/14 22:25:33 INFO  : file1.copy4.txt: Copied (new)
2024/11/14 22:25:33 DEBUG : file1.copy5.txt: md5 = 97b12263731d7984361fc7793afb9668 OK
2024/11/14 22:25:33 INFO  : file1.copy5.txt: Copied (new)
2024/11/14 22:25:33 DEBUG : file1.txt: md5 = 576a5de6c10e30cbf561091d74ea197a OK
2024/11/14 22:25:33 INFO  : file1.txt: Copied (new)
2024/11/14 22:25:33 DEBUG : subdir/file20.txt: md5 = d0b579ca0069e94762e197f1c5360b01 OK
2024/11/14 22:25:33 INFO  : subdir/file20.txt: Copied (new)
2024/11/14 22:25:33 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2'
2024/11/14 22:25:34 NOTICE: (01)  : test rmdirs
2024/11/14 22:25:34 NOTICE: (02)  : test initial bisync
2024/11/14 22:25:34 NOTICE: (03)  : bisync resync
2024/11/14 22:25:41 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:25:42 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:25:42 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:25:42 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:25:42 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:25:42 INFO  : Copying Path2 files to Path1
2024/11/14 22:25:42 INFO  : - Path2    Resync is copying files to         - Path1
2024/11/14 22:25:43 INFO  : - Path1    Resync is copying files to         - Path2
2024/11/14 22:25:43 INFO  : Resync updating listings
2024/11/14 22:25:43 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:25:43 INFO  : Bisync successful
2024/11/14 22:25:43 NOTICE: (04)  : test 1. delete path1 subdir file
2024/11/14 22:25:43 NOTICE: (05)  : delete-file TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/subdir/file20.txt
2024/11/14 22:25:46 INFO  : file20.txt: Deleted
2024/11/14 22:25:46 NOTICE: (06)  : test 2. run bisync without remove-empty-dirs
2024/11/14 22:25:46 NOTICE: (07)  : bisync
2024/11/14 22:25:53 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:25:55 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:25:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:25:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:25:55 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:25:55 INFO  : Building Path1 and Path2 listings
2024/11/14 22:25:55 INFO  : Path1 checking for diffs
2024/11/14 22:25:55 INFO  : - Path1    File was deleted          - subdir/file20.txt
2024/11/14 22:25:55 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2024/11/14 22:25:55 INFO  : Path2 checking for diffs
2024/11/14 22:25:55 INFO  : Applying changes
2024/11/14 22:25:55 INFO  : - Path2    Queue delete              - TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/subdir/file20.txt
2024/11/14 22:25:55 INFO  : - Path1    Do queued copies to                - Path2
2024/11/14 22:25:57 INFO  : subdir/file20.txt: Deleted
2024/11/14 22:25:57 INFO  : Updating listings
2024/11/14 22:25:58 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:25:58 INFO  : Bisync successful
2024/11/14 22:25:58 NOTICE: (08)  : test 3. confirm the subdir still exists on both paths
2024/11/14 22:25:58 NOTICE: (09)  : list-dirs TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/
2024/11/14 22:25:58 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2024/11/14 22:25:58 NOTICE: (10)  : list-dirs TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/
2024/11/14 22:25:59 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2024/11/14 22:25:59 NOTICE: (11)  : test 4. run bisync with remove-empty-dirs
2024/11/14 22:25:59 NOTICE: (12)  : bisync remove-empty-dirs
2024/11/14 22:26:06 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:26:07 INFO  : subdir: Set directory modification time (using SetModTime)
2024/11/14 22:26:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/11/14 22:26:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2024/11/14 22:26:07 INFO  : Synching Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" with Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:26:07 INFO  : Building Path1 and Path2 listings
2024/11/14 22:26:08 INFO  : Path1 checking for diffs
2024/11/14 22:26:08 INFO  : Path2 checking for diffs
2024/11/14 22:26:08 INFO  : No changes found
2024/11/14 22:26:08 INFO  : Updating listings
2024/11/14 22:26:08 INFO  : Validating listings for Path1 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/" vs Path2 "TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/"
2024/11/14 22:26:08 INFO  : Removing empty directories
2024/11/14 22:26:08 INFO  : subdir: Removing directory
2024/11/14 22:26:10 INFO  : subdir: Removing directory
2024/11/14 22:26:11 INFO  : Bisync successful
2024/11/14 22:26:11 NOTICE: (13)  : test 5. confirm the subdir has been removed on both paths
2024/11/14 22:26:11 NOTICE: (14)  : list-dirs TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path1/
2024/11/14 22:26:11 NOTICE: (15)  : list-dirs TestCryptDrive:rclone-test-canocun7vitu/222105ta/rmdirs/path2/
    bisync_test.go:556: TEST rmdirs PASSED
=== RUN   TestBisyncRemoteRemote/volatile
2024/11/14 22:26:13 DEBUG : path1: Making directory
2024/11/14 22:26:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path1"
2024/11/14 22:26:13 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/diudgf4526ccv228o9fs0ilue0/fb9eja2dfq85i3304q4elu1emo"
2024/11/14 22:26:15 DEBUG : path2: Making directory
2024/11/14 22:26:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path2"
2024/11/14 22:26:15 DEBUG : Creating backend with remote "TestDrive:crypt/qnugo7e43tsqih914k5jfalidm1vloj2ku95r34rg86h7pmg1d60/7tgu852c90q0tsj3b9b7ifpe84/diudgf4526ccv228o9fs0ilue0/b53o4pennvpoq8r59fti0dnbl8"
2024/11/14 22:26:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2024/11/14 22:26:17 DEBUG : Creating backend with remote "/tmp/222105ta/initdir/test_volatile-yoyuquz8"
2024/11/14 22:26:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2024/11/14 22:26:17 INFO  : file2.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : Local file system at /tmp/222105ta/initdir/test_volatile-yoyuquz8: Waiting for checks to finish
2024/11/14 22:26:17 DEBUG : Local file system at /tmp/222105ta/initdir/test_volatile-yoyuquz8: Waiting for transfers to finish
2024/11/14 22:26:17 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2024/11/14 22:26:17 INFO  : file3.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2024/11/14 22:26:17 INFO  : file4.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2024/11/14 22:26:17 INFO  : file5.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2024/11/14 22:26:17 INFO  : file6.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/11/14 22:26:17 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2024/11/14 22:26:17 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:26:17 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2024/11/14 22:26:17 INFO  : file7.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2024/11/14 22:26:17 INFO  : file8.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2024/11/14 22:26:17 INFO  : file1.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2024/11/14 22:26:17 DEBUG : Creating backend with remote "/tmp/222105ta/datadir/test_volatile-lovuhav7"
2024/11/14 22:26:17 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file10.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file11.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/14 22:26:17 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2024/11/14 22:26:17 INFO  : dummy.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:17 DEBUG : Local file system at /tmp/222105ta/datadir/test_volatile-lovuhav7: Waiting for checks to finish
2024/11/14 22:26:17 DEBUG : Local file system at /tmp/222105ta/datadir/test_volatile-lovuhav7: Waiting for transfers to finish
2024/11/14 22:26:17 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:26:17 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2024/11/14 22:26:17 INFO  : file1.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:26:17 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2024/11/14 22:26:17 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2024/11/14 22:26:17 INFO  : file10.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2024/11/14 22:26:17 INFO  : file2.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:26:17 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2024/11/14 22:26:17 INFO  : file11.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2024/11/14 22:26:17 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2024/11/14 22:26:17 INFO  : file5L.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2024/11/14 22:26:17 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2024/11/14 22:26:17 INFO  : file5R.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:26:17 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2024/11/14 22:26:17 INFO  : file6.txt: Copied (new)
2024/11/14 22:26:17 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/11/14 22:26:17 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2024/11/14 22:26:17 INFO  : file7.txt: Copied (new)
2024/11/14 22:26:18 NOTICE: checking initFs Local file system at /tmp/222105ta/initdir/test_volatile-yoyuquz8
2024/11/14 22:26:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path1': Waiting for checks to finish
2024/11/14 22:26:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path1': Waiting for transfers to finish
2024/11/14 22:26:23 DEBUG : file1.txt: md5 = 375a34a6ffcd0134f855988baf272edd OK
2024/11/14 22:26:23 INFO  : file1.txt: Copied (new)
2024/11/14 22:26:23 DEBUG : file2.txt: md5 = 76b163a83a5d8e4253c9866d7be09354 OK
2024/11/14 22:26:23 INFO  : file2.txt: Copied (new)
2024/11/14 22:26:23 DEBUG : RCLONE_TEST: md5 = 1cabb57711441d588a47537b9c818e29 OK
2024/11/14 22:26:23 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:26:23 DEBUG : file3.txt: md5 = fcbb12f06020b36f96cd9dc301d000fe OK
2024/11/14 22:26:23 INFO  : file3.txt: Copied (new)
2024/11/14 22:26:24 DEBUG : file4.txt: md5 = 5b891cac443be2ceef7ef38f67dd819a OK
2024/11/14 22:26:24 INFO  : file4.txt: Copied (new)
2024/11/14 22:26:25 DEBUG : file5.txt: md5 = 0a05f60cec0c08e12eb301a886d2da83 OK
2024/11/14 22:26:25 INFO  : file5.txt: Copied (new)
2024/11/14 22:26:25 DEBUG : file6.txt: md5 = 6d75e515f8be6d296fa1a9684de088ba OK
2024/11/14 22:26:25 INFO  : file6.txt: Copied (new)
2024/11/14 22:26:25 DEBUG : file7.txt: md5 = 0f2e5a1321c12937e3267367c57cef36 OK
2024/11/14 22:26:25 INFO  : file7.txt: Copied (new)
2024/11/14 22:26:26 DEBUG : file8.txt: md5 = 943f340e9c41b6ec06c215d8213fbdb8 OK
2024/11/14 22:26:26 INFO  : file8.txt: Copied (new)
2024/11/14 22:26:26 NOTICE: checking Path1 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path1'
2024/11/14 22:26:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/11/14 22:26:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path2': Waiting for checks to finish
2024/11/14 22:26:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path2': Waiting for transfers to finish
2024/11/14 22:26:30 DEBUG : RCLONE_TEST: md5 = 3fef1b90e5330dbc4cbbcfddd00ee1f3 OK
2024/11/14 22:26:30 INFO  : RCLONE_TEST: Copied (new)
2024/11/14 22:26:30 DEBUG : file3.txt: md5 = b83caff6b20af319cb379279b7a00f72 OK
2024/11/14 22:26:30 INFO  : file3.txt: Copied (new)
2024/11/14 22:26:30 DEBUG : file1.txt: md5 = c53a8f7155fa4414295b377d766aeb80 OK
2024/11/14 22:26:30 INFO  : file1.txt: Copied (new)
2024/11/14 22:26:30 DEBUG : file2.txt: md5 = 939df38214bdaab609f6a6f67bbb4f26 OK
2024/11/14 22:26:30 INFO  : file2.txt: Copied (new)
2024/11/14 22:26:32 DEBUG : file4.txt: md5 = 9c526eb29f8811557dbd3bccc0fc5d5b OK
2024/11/14 22:26:32 INFO  : file4.txt: Copied (new)
2024/11/14 22:26:32 DEBUG : file5.txt: md5 = a6bc0f6b85d08421a3a6934b76612efe OK
2024/11/14 22:26:32 INFO  : file5.txt: Copied (new)
2024/11/14 22:26:32 DEBUG : file6.txt: md5 = cbcea169c7a6660f673c32d06afb7eda OK
2024/11/14 22:26:32 INFO  : file6.txt: Copied (new)
2024/11/14 22:26:32 DEBUG : file7.txt: md5 = 9e67c0ec3fc32d7f2abcfcc4fe0dd7f7 OK
2024/11/14 22:26:32 INFO  : file7.txt: Copied (new)
2024/11/14 22:26:34 DEBUG : file8.txt: md5 = 9e938d658ae657a8a94ea49a10e95ff0 OK
2024/11/14 22:26:34 INFO  : file8.txt: Copied (new)
2024/11/14 22:26:34 NOTICE: checking path2 Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu/222105ta/volatile/path2'
2024/11/14 22:26:34 NOTICE: (01)  : test volatile
2024/11/14 22:26:34 NOTICE: (02)  : test initial bisync
2024/11/14 22:26:34 NOTICE: (03)  : bisync resync
    bisync_test.go:924: skipping 'volatile' test on non-local as it requires uploading 100 files
2024/11/14 22:26:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-canocun7vitu': Purge remote
--- PASS: TestBisyncRemoteRemote (335.04s)
    --- PASS: TestBisyncRemoteRemote/resync (131.00s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (119.21s)
    --- PASS: TestBisyncRemoteRemote/rmdirs (57.82s)
    --- SKIP: TestBisyncRemoteRemote/volatile (21.37s)
PASS
2024/11/14 22:26:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sokover2kiwo': Purge remote
2024/11/14 22:26:36 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 5m36.665343692s (try 3/5)