"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncLocalRemote$/^(compare_all|max_delete_path1|rclone_args|rmdirs)$|^TestBisyncRemoteLocal$/^(createemptysrcdirs|dry_run)$|^TestBisyncRemoteRemote$/^(check_filename|dry_run)$'" - Starting (try 3/5)
2025/05/09 03:29:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-duyupoc4cewe"
2025/05/09 03:29:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/09 03:29:42 DEBUG : Starting OpenDrive session with ID: 3c8e5a703d409c1a59131af7acd56e932f191de4cbc377e69ecb45fed25ab059
2025/05/09 03:29:42 DEBUG : Creating backend with remote "/tmp/rclone959053667"
=== RUN   TestBisyncRemoteLocal
2025/05/09 03:29:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rogitis9fana"
2025/05/09 03:29:43 DEBUG : Starting OpenDrive session with ID: 90085f9cdb0abef5aa4089130555589aa3e54ac012613b54ab2e04204d16bb9e
2025/05/09 03:29:43 NOTICE: remote: TestOpenDrive:rclone-test-rogitis9fana
=== RUN   TestBisyncRemoteLocal/createemptysrcdirs
2025/05/09 03:29:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rogitis9fana/032943sa"
2025/05/09 03:29:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/09 03:29:44 DEBUG : Starting OpenDrive session with ID: 70b689784251612dc6c3b5ac2502ac4108b74ffc351dd5427609ffde9d520c11
2025/05/09 03:29:44 INFO  : path1: Making directory
2025/05/09 03:29:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1"
2025/05/09 03:29:46 DEBUG : Starting OpenDrive session with ID: 65ec62a5d8631f90ffd7020dec7deb2a7bb10ffbd7d0dabfcbd24e5384e22a9c
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/tmp/032943sa"
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/tmp/032943sa/createemptysrcdirs/path2"
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_createemptysrcdirs/initial"
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/tmp/032943sa/initdir/test_createemptysrcdirs-hoyoqiz6"
2025/05/09 03:29:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : Local file system at /tmp/032943sa/initdir/test_createemptysrcdirs-hoyoqiz6: Waiting for checks to finish
2025/05/09 03:29:47 DEBUG : Local file system at /tmp/032943sa/initdir/test_createemptysrcdirs-hoyoqiz6: Waiting for transfers to finish
2025/05/09 03:29:47 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/05/09 03:29:47 INFO  : file1.copy1.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/05/09 03:29:47 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/05/09 03:29:47 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:29:47 INFO  : file1.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/05/09 03:29:47 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/05/09 03:29:47 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:29:47 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:29:47 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:29:47 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_createemptysrcdirs/modfiles"
2025/05/09 03:29:47 DEBUG : Creating backend with remote "/tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4"
2025/05/09 03:29:47 DEBUG : placeholder.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : Local file system at /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4: Waiting for checks to finish
2025/05/09 03:29:47 DEBUG : Local file system at /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4: Waiting for transfers to finish
2025/05/09 03:29:47 DEBUG : placeholder.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:47 DEBUG : placeholder.txt.4db661ca.partial: renamed to: placeholder.txt
2025/05/09 03:29:47 INFO  : placeholder.txt: Copied (new)
2025/05/09 03:29:47 ERROR : : error listing: directory not found
2025/05/09 03:29:47 ERROR : : error listing: directory not found
2025/05/09 03:29:47 NOTICE: checking initFs Local file system at /tmp/032943sa/initdir/test_createemptysrcdirs-hoyoqiz6
2025/05/09 03:29:47 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:29:47 DEBUG : OpenDrive root 'rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1': Waiting for checks to finish
2025/05/09 03:29:47 DEBUG : OpenDrive root 'rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1': Waiting for transfers to finish
2025/05/09 03:29:48 ERROR : file1.copy1.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404)
2025/05/09 03:29:50 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:50 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:29:50 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:50 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:29:50 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:50 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:29:51 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:29:51 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:51 INFO  : file1.txt: Copied (new)
2025/05/09 03:29:51 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:29:51 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:29:52 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:29:52 INFO  : RCLONE_TEST: Copied (new)
    bisync_test.go:1943: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1943
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:458
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Received unexpected error:
        	            	failed to get folder list: Directory doesn't exist (Error 404)
        	Test:       	TestBisyncRemoteLocal/createemptysrcdirs
        	Messages:   	setting up path1
2025/05/09 03:29:52 NOTICE: checking Path1 OpenDrive root 'rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1'
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Should be true
        	Test:       	TestBisyncRemoteLocal/createemptysrcdirs
        	Messages:   	listing wrong, want
        	            	  RCLONE_TEST (109), file1.copy1.txt (0), file1.copy2.txt (0), file1.copy3.txt (0), file1.copy4.txt (0), file1.copy5.txt (0), file1.txt (0) got
        	            	  RCLONE_TEST (109), file1.copy2.txt (0), file1.copy3.txt (0), file1.copy4.txt (0), file1.copy5.txt (0), file1.txt (0)
    fstest.go:197: Not found "file1.copy1.txt"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncRemoteLocal/createemptysrcdirs
        	Messages:   	1 objects not found
2025/05/09 03:30:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:30:01 DEBUG : Local file system at /tmp/032943sa/createemptysrcdirs/path2: Waiting for checks to finish
2025/05/09 03:30:01 DEBUG : Local file system at /tmp/032943sa/createemptysrcdirs/path2: Waiting for transfers to finish
2025/05/09 03:30:01 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/05/09 03:30:01 INFO  : file1.copy1.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/05/09 03:30:01 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/05/09 03:30:01 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/05/09 03:30:01 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/05/09 03:30:01 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:30:01 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:30:01 INFO  : file1.txt: Copied (new)
2025/05/09 03:30:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:30:01 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:30:01 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:30:01 NOTICE: checking path2 Local file system at /tmp/032943sa/createemptysrcdirs/path2
2025/05/09 03:30:01 NOTICE: (01)  : test createemptysrcdirs
2025/05/09 03:30:01 NOTICE: (02)  : test initial bisync
2025/05/09 03:30:01 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/ placeholder.txt
2025/05/09 03:30:01 NOTICE: (04)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.txt
2025/05/09 03:30:03 INFO  : placeholder.txt: Copied (replaced existing) to: file1.txt
2025/05/09 03:30:03 NOTICE: (05)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy1.txt
2025/05/09 03:30:06 INFO  : placeholder.txt: Copied (new) to: file1.copy1.txt
2025/05/09 03:30:06 NOTICE: (06)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy2.txt
2025/05/09 03:30:08 INFO  : placeholder.txt: Copied (replaced existing) to: file1.copy2.txt
2025/05/09 03:30:08 NOTICE: (07)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy3.txt
2025/05/09 03:30:10 INFO  : placeholder.txt: Copied (replaced existing) to: file1.copy3.txt
2025/05/09 03:30:10 NOTICE: (08)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy4.txt
2025/05/09 03:30:13 INFO  : placeholder.txt: Copied (replaced existing) to: file1.copy4.txt
2025/05/09 03:30:13 NOTICE: (09)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy5.txt
2025/05/09 03:30:15 INFO  : placeholder.txt: Copied (replaced existing) to: file1.copy5.txt
2025/05/09 03:30:15 NOTICE: (10)  : bisync resync
2025/05/09 03:30:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:30:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:30:18 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:18 INFO  : Copying Path2 files to Path1
2025/05/09 03:30:18 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:30:18 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:30:18 INFO  : file1.copy1.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : file1.copy2.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : file1.copy3.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : file1.copy4.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : file1.copy5.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : file1.txt: Updated modification time in destination
2025/05/09 03:30:18 INFO  : Resync updating listings
2025/05/09 03:30:18 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:18 INFO  : Bisync successful
2025/05/09 03:30:18 NOTICE: (11)  : test 1. Create an empty dir on Path1 by creating subdir/placeholder.txt and then deleting the placeholder
2025/05/09 03:30:18 NOTICE: (12)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ subdir/placeholder.txt
2025/05/09 03:30:21 INFO  : placeholder.txt: Copied (new) to: subdir/placeholder.txt
2025/05/09 03:30:21 NOTICE: (13)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ subdir
2025/05/09 03:30:22 NOTICE: (14)  : delete-file TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/subdir/placeholder.txt
2025/05/09 03:30:24 INFO  : placeholder.txt: Deleted
2025/05/09 03:30:24 NOTICE: (15)  : test 2. Run bisync without --create-empty-src-dirs
2025/05/09 03:30:24 NOTICE: (16)  : bisync
2025/05/09 03:30:26 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:30:26 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:30:26 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:26 INFO  : Building Path1 and Path2 listings
2025/05/09 03:30:26 INFO  : Path1 checking for diffs
2025/05/09 03:30:26 INFO  : - Path1    File is new               - modtime_write_test
2025/05/09 03:30:26 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/05/09 03:30:26 INFO  : Path2 checking for diffs
2025/05/09 03:30:26 INFO  : Applying changes
2025/05/09 03:30:26 INFO  : - Path1    Queue copy to Path2       - /tmp/032943sa/createemptysrcdirs/path2/modtime_write_test
2025/05/09 03:30:26 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:30:26 INFO  : Updating listings
2025/05/09 03:30:27 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:27 INFO  : Bisync successful
2025/05/09 03:30:27 NOTICE: (17)  : test 3. Confirm the subdir exists only on Path1 and not Path2
2025/05/09 03:30:27 NOTICE: (18)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:27 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:27 NOTICE: (19)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:30:27 NOTICE: (20)  : test 4.Run bisync WITH --create-empty-src-dirs
2025/05/09 03:30:27 NOTICE: (21)  : bisync create-empty-src-dirs
2025/05/09 03:30:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:30:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:30:29 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:29 INFO  : Building Path1 and Path2 listings
2025/05/09 03:30:29 INFO  : Path1 checking for diffs
2025/05/09 03:30:29 INFO  : - Path1    File is new               - subdir
2025/05/09 03:30:29 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/05/09 03:30:29 INFO  : Path2 checking for diffs
2025/05/09 03:30:29 INFO  : Applying changes
2025/05/09 03:30:29 INFO  : - Path1    Queue copy to Path2       - /tmp/032943sa/createemptysrcdirs/path2/subdir
2025/05/09 03:30:29 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:30:29 INFO  : subdir: Making directory
2025/05/09 03:30:29 INFO  : Updating listings
2025/05/09 03:30:29 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:29 INFO  : Bisync successful
2025/05/09 03:30:29 NOTICE: (22)  : test 5. Confirm the subdir exists on both paths
2025/05/09 03:30:29 NOTICE: (23)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:30 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:30 NOTICE: (24)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:30:30 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:30 NOTICE: (25)  : test 6. Delete the empty dir on Path1 using purge-children (and also add files so the path isn't empty)
2025/05/09 03:30:30 NOTICE: (26)  : purge-children TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:31 NOTICE: (27)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.txt
2025/05/09 03:30:34 INFO  : placeholder.txt: Copied (new) to: file1.txt
2025/05/09 03:30:34 NOTICE: (28)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy1.txt
2025/05/09 03:30:37 INFO  : placeholder.txt: Copied (new) to: file1.copy1.txt
2025/05/09 03:30:37 NOTICE: (29)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy2.txt
2025/05/09 03:30:40 INFO  : placeholder.txt: Copied (new) to: file1.copy2.txt
2025/05/09 03:30:40 NOTICE: (30)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy3.txt
2025/05/09 03:30:43 INFO  : placeholder.txt: Copied (new) to: file1.copy3.txt
2025/05/09 03:30:43 NOTICE: (31)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy4.txt
2025/05/09 03:30:46 INFO  : placeholder.txt: Copied (new) to: file1.copy4.txt
2025/05/09 03:30:46 NOTICE: (32)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy5.txt
2025/05/09 03:30:49 INFO  : placeholder.txt: Copied (new) to: file1.copy5.txt
2025/05/09 03:30:49 NOTICE: (33)  : test 7. Run bisync without --create-empty-src-dirs
2025/05/09 03:30:49 NOTICE: (34)  : bisync
2025/05/09 03:30:51 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:30:51 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:30:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:30:51 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:51 INFO  : Building Path1 and Path2 listings
2025/05/09 03:30:51 INFO  : Path1 checking for diffs
2025/05/09 03:30:51 INFO  : - Path1    File was deleted          - RCLONE_TEST
2025/05/09 03:30:51 INFO  : - Path1    File was deleted          - subdir
2025/05/09 03:30:51 INFO  : Path1:    2 changes:    0 new,    0 modified,    2 deleted
2025/05/09 03:30:51 INFO  : Path2 checking for diffs
2025/05/09 03:30:51 INFO  : - Path2    File was deleted          - subdir
2025/05/09 03:30:51 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/05/09 03:30:51 INFO  : Applying changes
2025/05/09 03:30:51 INFO  : - Path2    Queue delete              - /tmp/032943sa/createemptysrcdirs/path2/RCLONE_TEST
2025/05/09 03:30:51 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:30:51 INFO  : RCLONE_TEST: Deleted
2025/05/09 03:30:51 INFO  : Updating listings
2025/05/09 03:30:51 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:51 INFO  : Bisync successful
2025/05/09 03:30:51 NOTICE: (35)  : test 8. Confirm the subdir exists only on Path2 and not Path1
2025/05/09 03:30:51 NOTICE: (36)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:51 NOTICE: (37)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:30:51 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:51 NOTICE: (38)  : test 9. Reset, do the delete again, and run bisync WITH --create-empty-src-dirs
2025/05/09 03:30:51 NOTICE: (39)  : bisync resync create-empty-src-dirs
2025/05/09 03:30:53 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:30:53 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:30:53 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:30:53 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:53 INFO  : Copying Path2 files to Path1
2025/05/09 03:30:53 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:30:54 INFO  : subdir: Making directory
2025/05/09 03:30:54 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:30:54 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:30:54 INFO  : Resync updating listings
2025/05/09 03:30:54 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:30:54 INFO  : Bisync successful
2025/05/09 03:30:54 NOTICE: (40)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:54 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:55 NOTICE: (41)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:30:55 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:30:55 NOTICE: (42)  : purge-children TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:30:56 NOTICE: (43)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.txt
2025/05/09 03:30:59 INFO  : placeholder.txt: Copied (new) to: file1.txt
2025/05/09 03:30:59 NOTICE: (44)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy1.txt
2025/05/09 03:31:02 INFO  : placeholder.txt: Copied (new) to: file1.copy1.txt
2025/05/09 03:31:02 NOTICE: (45)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy2.txt
2025/05/09 03:31:05 INFO  : placeholder.txt: Copied (new) to: file1.copy2.txt
2025/05/09 03:31:05 NOTICE: (46)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy3.txt
2025/05/09 03:31:08 INFO  : placeholder.txt: Copied (new) to: file1.copy3.txt
2025/05/09 03:31:08 NOTICE: (47)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy4.txt
2025/05/09 03:31:10 INFO  : placeholder.txt: Copied (new) to: file1.copy4.txt
2025/05/09 03:31:10 NOTICE: (48)  : copy-as /tmp/032943sa/datadir/test_createemptysrcdirs-suwapid4/placeholder.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/ file1.copy5.txt
2025/05/09 03:31:13 INFO  : placeholder.txt: Copied (new) to: file1.copy5.txt
2025/05/09 03:31:13 NOTICE: (49)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:31:14 NOTICE: (50)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:31:14 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:31:14 NOTICE: (51)  : bisync create-empty-src-dirs
2025/05/09 03:31:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:31:16 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:31:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:31:16 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:31:16 INFO  : Building Path1 and Path2 listings
2025/05/09 03:31:16 INFO  : Path1 checking for diffs
2025/05/09 03:31:16 INFO  : - Path1    File was deleted          - subdir
2025/05/09 03:31:16 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/05/09 03:31:16 INFO  : Path2 checking for diffs
2025/05/09 03:31:16 INFO  : Applying changes
2025/05/09 03:31:16 INFO  : - Path2    Queue delete              - /tmp/032943sa/createemptysrcdirs/path2/subdir
2025/05/09 03:31:16 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:31:16 INFO  : subdir: Removing directory
2025/05/09 03:31:16 INFO  : Updating listings
2025/05/09 03:31:16 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:31:16 INFO  : Bisync successful
2025/05/09 03:31:16 NOTICE: (52)  : test 10. Confirm the subdir has been removed on both paths
2025/05/09 03:31:16 NOTICE: (53)  : list-dirs TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/
2025/05/09 03:31:16 NOTICE: (54)  : list-dirs /tmp/032943sa/createemptysrcdirs/path2/
2025/05/09 03:31:16 NOTICE: (55)  : test 11. bisync again (because if we leave subdir in listings, test will fail due to mismatched modtime)
2025/05/09 03:31:16 NOTICE: (56)  : bisync create-empty-src-dirs
2025/05/09 03:31:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:31:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:31:18 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" with Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:31:18 INFO  : Building Path1 and Path2 listings
2025/05/09 03:31:18 INFO  : Path1 checking for diffs
2025/05/09 03:31:18 INFO  : Path2 checking for diffs
2025/05/09 03:31:18 INFO  : No changes found
2025/05/09 03:31:18 INFO  : Updating listings
2025/05/09 03:31:18 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/createemptysrcdirs/path1/" vs Path2 "/tmp/032943sa/createemptysrcdirs/path2/"
2025/05/09 03:31:18 INFO  : Bisync successful
2025/05/09 03:31:18 NOTICE: ----------------------------------------------------------
2025/05/09 03:31:18 NOTICE: | MISCOMPARE  -Golden vs +Results for  test.log
2025/05/09 03:31:18 NOTICE: | @@ -47,2 +47,6 @@
2025/05/09 03:31:18 NOTICE: | -INFO  : Path2 checking for diffs
2025/05/09 03:31:18 NOTICE: | -INFO  : No changes found
2025/05/09 03:31:18 NOTICE: | +INFO  : - Path1    File is new               - modtime_write_test
2025/05/09 03:31:18 NOTICE: | +INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/05/09 03:31:18 NOTICE: | +INFO  : Path2 checking for diffs
2025/05/09 03:31:18 NOTICE: | +INFO  : Applying changes
2025/05/09 03:31:18 NOTICE: | +INFO  : - Path1    Queue copy to Path2       - {path2/}modtime_write_test
2025/05/09 03:31:18 NOTICE: | +INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:31:18 NOTICE: ----------------------------------------------------------
    bisync_test.go:560: TEST createemptysrcdirs FAILED - 1 MISCOMPARED FILES
=== RUN   TestBisyncRemoteLocal/dry_run
2025/05/09 03:31:19 INFO  : path1: Making directory
2025/05/09 03:31:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1"
2025/05/09 03:31:19 DEBUG : Starting OpenDrive session with ID: 9f240ec3bb46438ef126bb8bac4c2b0c24c63d9153da1c465feaddf8903fd954
2025/05/09 03:31:21 DEBUG : Creating backend with remote "/tmp/032943sa/dry_run/path2"
2025/05/09 03:31:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_dry_run/initial"
2025/05/09 03:31:21 DEBUG : Creating backend with remote "/tmp/032943sa/initdir/test_dry_run-muwecom7"
2025/05/09 03:31:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : Local file system at /tmp/032943sa/initdir/test_dry_run-muwecom7: Waiting for checks to finish
2025/05/09 03:31:21 DEBUG : Local file system at /tmp/032943sa/initdir/test_dry_run-muwecom7: Waiting for transfers to finish
2025/05/09 03:31:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:31:21 INFO  : file1.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:31:21 INFO  : file2.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:31:21 INFO  : file3.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:31:21 INFO  : file4.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:31:21 INFO  : file5.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:31:21 INFO  : file6.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:21 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:31:21 INFO  : file7.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:31:21 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:31:21 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:31:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_dry_run/modfiles"
2025/05/09 03:31:21 DEBUG : Creating backend with remote "/tmp/032943sa/datadir/test_dry_run-japicil6"
2025/05/09 03:31:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : Local file system at /tmp/032943sa/datadir/test_dry_run-japicil6: Waiting for checks to finish
2025/05/09 03:31:21 DEBUG : Local file system at /tmp/032943sa/datadir/test_dry_run-japicil6: Waiting for transfers to finish
2025/05/09 03:31:21 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:31:21 DEBUG : file10.txt.925c801f.partial: renamed to: file10.txt
2025/05/09 03:31:21 INFO  : file10.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:31:21 DEBUG : file11.txt.925c801f.partial: renamed to: file11.txt
2025/05/09 03:31:21 INFO  : file11.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/05/09 03:31:21 DEBUG : file2.txt.f8ac1735.partial: renamed to: file2.txt
2025/05/09 03:31:21 INFO  : file2.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/05/09 03:31:21 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/05/09 03:31:21 INFO  : file5L.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:31:21 DEBUG : file6.txt.925c801f.partial: renamed to: file6.txt
2025/05/09 03:31:21 INFO  : file6.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:31:21 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/05/09 03:31:21 INFO  : file1.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/05/09 03:31:21 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/05/09 03:31:21 INFO  : file5R.txt: Copied (new)
2025/05/09 03:31:21 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:31:21 DEBUG : file7.txt.925c801f.partial: renamed to: file7.txt
2025/05/09 03:31:21 INFO  : file7.txt: Copied (new)
2025/05/09 03:31:21 ERROR : : error listing: directory not found
2025/05/09 03:31:21 ERROR : : error listing: directory not found
2025/05/09 03:31:21 NOTICE: checking initFs Local file system at /tmp/032943sa/initdir/test_dry_run-muwecom7
2025/05/09 03:31:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:31:21 DEBUG : OpenDrive root 'rclone-test-rogitis9fana/032943sa/dry_run/path1': Waiting for checks to finish
2025/05/09 03:31:21 DEBUG : OpenDrive root 'rclone-test-rogitis9fana/032943sa/dry_run/path1': Waiting for transfers to finish
2025/05/09 03:31:22 ERROR : file1.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404)
2025/05/09 03:31:24 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:24 INFO  : file4.txt: Copied (new)
2025/05/09 03:31:24 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:24 INFO  : file5.txt: Copied (new)
2025/05/09 03:31:24 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:24 INFO  : file2.txt: Copied (new)
2025/05/09 03:31:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:24 INFO  : file3.txt: Copied (new)
2025/05/09 03:31:25 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:31:25 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:25 INFO  : file7.txt: Copied (new)
2025/05/09 03:31:26 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:31:26 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:31:26 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:26 INFO  : file6.txt: Copied (new)
    bisync_test.go:1943: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1943
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:458
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Received unexpected error:
        	            	failed to get folder list: Directory doesn't exist (Error 404)
        	Test:       	TestBisyncRemoteLocal/dry_run
        	Messages:   	setting up path1
2025/05/09 03:31:26 NOTICE: checking Path1 OpenDrive root 'rclone-test-rogitis9fana/032943sa/dry_run/path1'
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Should be true
        	Test:       	TestBisyncRemoteLocal/dry_run
        	Messages:   	listing wrong, want
        	            	  RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0) got
        	            	  RCLONE_TEST (109), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0)
    fstest.go:197: Not found "file1.txt"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:460
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncRemoteLocal/dry_run
        	Messages:   	1 objects not found
2025/05/09 03:31:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:31:35 DEBUG : Local file system at /tmp/032943sa/dry_run/path2: Waiting for checks to finish
2025/05/09 03:31:35 DEBUG : Local file system at /tmp/032943sa/dry_run/path2: Waiting for transfers to finish
2025/05/09 03:31:35 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:31:35 INFO  : file1.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:31:35 INFO  : file2.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:31:35 INFO  : file3.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:31:35 INFO  : file4.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:31:35 INFO  : file5.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:31:35 INFO  : file6.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:31:35 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:31:35 INFO  : file7.txt: Copied (new)
2025/05/09 03:31:35 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:31:35 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:31:35 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:31:35 NOTICE: checking path2 Local file system at /tmp/032943sa/dry_run/path2
2025/05/09 03:31:35 NOTICE: (01)  : test dry-run
2025/05/09 03:31:35 NOTICE: (02)  : test initial bisync
2025/05/09 03:31:35 NOTICE: (03)  : bisync resync
2025/05/09 03:31:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:31:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:31:38 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" with Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:31:38 INFO  : Copying Path2 files to Path1
2025/05/09 03:31:38 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:31:40 INFO  : file1.txt: Copied (new)
2025/05/09 03:31:40 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:31:40 INFO  : Resync updating listings
2025/05/09 03:31:40 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" vs Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:31:40 INFO  : Bisync successful
2025/05/09 03:31:40 NOTICE: (04)  : test new on path2 - file10
2025/05/09 03:31:40 NOTICE: (05)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file10.txt /tmp/032943sa/dry_run/path2/
2025/05/09 03:31:40 INFO  : file10.txt: Copied (new)
2025/05/09 03:31:40 NOTICE: (06)  : test newer on path2 - file1
2025/05/09 03:31:40 NOTICE: (07)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file1.txt /tmp/032943sa/dry_run/path2/
2025/05/09 03:31:40 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:31:40 NOTICE: (08)  : test new on path1 - file11
2025/05/09 03:31:40 NOTICE: (09)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file11.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/
2025/05/09 03:31:43 INFO  : file11.txt: Copied (new)
2025/05/09 03:31:43 NOTICE: (10)  : test newer on path1 - file2
2025/05/09 03:31:43 NOTICE: (11)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file2.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/
2025/05/09 03:31:46 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:31:46 NOTICE: (12)  : test deleted on path2 - file3
2025/05/09 03:31:46 NOTICE: (13)  : delete-file /tmp/032943sa/dry_run/path2/file3.txt
2025/05/09 03:31:46 INFO  : file3.txt: Deleted
2025/05/09 03:31:46 NOTICE: (14)  : test deleted on path1 - file4
2025/05/09 03:31:46 NOTICE: (15)  : delete-file TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file4.txt
2025/05/09 03:31:47 INFO  : file4.txt: Deleted
2025/05/09 03:31:47 NOTICE: (16)  : test changed on path2 and on path1 - file5 (file5R, file5L)
2025/05/09 03:31:47 NOTICE: (17)  : touch-glob 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/ file5R.txt
2025/05/09 03:31:47 NOTICE: (18)  : copy-as /tmp/032943sa/datadir/test_dry_run-japicil6/file5R.txt /tmp/032943sa/dry_run/path2/ file5.txt
2025/05/09 03:31:47 INFO  : file5R.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:31:47 NOTICE: (19)  : touch-glob 2001-03-04 /tmp/032943sa/datadir/test_dry_run-japicil6/ file5L.txt
2025/05/09 03:31:47 NOTICE: (20)  : copy-as /tmp/032943sa/datadir/test_dry_run-japicil6/file5L.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/ file5.txt
2025/05/09 03:31:50 INFO  : file5L.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:31:50 NOTICE: (21)  : test newer on path2 and deleted on path1 - file6
2025/05/09 03:31:50 NOTICE: (22)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file6.txt /tmp/032943sa/dry_run/path2/
2025/05/09 03:31:50 INFO  : file6.txt: Copied (replaced existing)
2025/05/09 03:31:50 NOTICE: (23)  : delete-file TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file6.txt
2025/05/09 03:31:50 INFO  : file6.txt: Deleted
2025/05/09 03:31:50 NOTICE: (24)  : test newer on path1 and deleted on path2 - file7
2025/05/09 03:31:50 NOTICE: (25)  : touch-copy 2001-01-02 /tmp/032943sa/datadir/test_dry_run-japicil6/file7.txt TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/
2025/05/09 03:31:53 INFO  : file7.txt: Copied (replaced existing)
2025/05/09 03:31:53 NOTICE: (26)  : delete-file /tmp/032943sa/dry_run/path2/file7.txt
2025/05/09 03:31:53 INFO  : file7.txt: Deleted
2025/05/09 03:31:53 NOTICE: (27)  : test sync with dry-run and resync
2025/05/09 03:31:53 NOTICE: (28)  : bisync dry-run resync
2025/05/09 03:31:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:31:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:31:55 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" with Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:31:55 INFO  : Copying Path2 files to Path1
2025/05/09 03:31:55 NOTICE: - Path2    Resync is copying files to         - Path1
2025/05/09 03:31:55 NOTICE: file10.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:55 NOTICE: file4.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:31:55 NOTICE: file6.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:55 NOTICE: - Path1    Resync is copying files to         - Path2
2025/05/09 03:31:55 NOTICE: file11.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:55 NOTICE: file3.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:31:55 NOTICE: file7.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:55 NOTICE: file1.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:31:55 NOTICE: file2.txt: Skipped copy as --dry-run is set (size 13)
2025/05/09 03:31:55 NOTICE: file5.txt: Skipped copy as --dry-run is set (size 39)
2025/05/09 03:31:55 INFO  : Resync updating listings
2025/05/09 03:31:55 INFO  : Bisync successful
2025/05/09 03:31:55 NOTICE: (29)  : copy-listings dryrun-resync
2025/05/09 03:31:55 NOTICE: (30)  : test sync with dry-run
2025/05/09 03:31:55 NOTICE: (31)  : bisync dry-run
2025/05/09 03:31:57 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:31:57 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:31:57 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" with Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:31:57 INFO  : Building Path1 and Path2 listings
2025/05/09 03:31:57 INFO  : Path1 checking for diffs
2025/05/09 03:31:57 NOTICE: - Path1    File changed: size (larger), time (newer) - file2.txt
2025/05/09 03:31:57 NOTICE: - Path1    File was deleted          - file4.txt
2025/05/09 03:31:57 NOTICE: - Path1    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:31:57 NOTICE: - Path1    File was deleted          - file6.txt
2025/05/09 03:31:57 NOTICE: - Path1    File changed: size (larger), time (newer) - file7.txt
2025/05/09 03:31:57 NOTICE: - Path1    File is new               - file11.txt
2025/05/09 03:31:57 INFO  : Path1:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:31:57 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:31:57 INFO  : Path2 checking for diffs
2025/05/09 03:31:57 NOTICE: - Path2    File changed: size (larger), time (newer) - file1.txt
2025/05/09 03:31:57 NOTICE: - Path2    File was deleted          - file3.txt
2025/05/09 03:31:57 NOTICE: - Path2    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:31:57 NOTICE: - Path2    File changed: size (larger), time (newer) - file6.txt
2025/05/09 03:31:57 NOTICE: - Path2    File was deleted          - file7.txt
2025/05/09 03:31:57 NOTICE: - Path2    File is new               - file10.txt
2025/05/09 03:31:57 INFO  : Path2:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:31:57 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:31:57 INFO  : Applying changes
2025/05/09 03:31:57 INFO  : Checking potential conflicts...
2025/05/09 03:31:58 ERROR : file5.txt: md5 differ
2025/05/09 03:31:58 NOTICE: Local file system at /tmp/032943sa/dry_run/path2: 1 differences found
2025/05/09 03:31:58 NOTICE: Local file system at /tmp/032943sa/dry_run/path2: 1 errors while checking
2025/05/09 03:31:58 INFO  : Finished checking the potential conflicts. 1 differences found
2025/05/09 03:31:58 NOTICE: - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file11.txt
2025/05/09 03:31:58 NOTICE: - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file2.txt
2025/05/09 03:31:58 NOTICE: - Path2    Queue delete              - /tmp/032943sa/dry_run/path2/file4.txt
2025/05/09 03:31:58 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/05/09 03:31:58 NOTICE: file5.txt: Skipped rename as --dry-run is set
2025/05/09 03:31:58 NOTICE: - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file5.txt.conflict1
2025/05/09 03:31:58 NOTICE: file5.txt: Skipped rename as --dry-run is set
2025/05/09 03:31:58 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file5.txt.conflict2
2025/05/09 03:31:58 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file6.txt
2025/05/09 03:31:58 NOTICE: - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file7.txt
2025/05/09 03:31:58 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file1.txt
2025/05/09 03:31:58 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file10.txt
2025/05/09 03:31:58 NOTICE: - Path1    Queue delete              - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file3.txt
2025/05/09 03:31:58 NOTICE: - Path2    Do queued copies to                - Path1
2025/05/09 03:31:58 NOTICE: file10.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:58 NOTICE: file6.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:58 NOTICE: file1.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:58 NOTICE: file3.txt: Skipped delete as --dry-run is set (size 0)
2025/05/09 03:31:58 NOTICE: - Path1    Do queued copies to                - Path2
2025/05/09 03:31:58 NOTICE: file11.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:58 NOTICE: file7.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:31:58 NOTICE: file2.txt: Skipped copy as --dry-run is set (size 13)
2025/05/09 03:31:58 NOTICE: file4.txt: Skipped delete as --dry-run is set (size 0)
2025/05/09 03:31:58 INFO  : Updating listings
2025/05/09 03:31:58 INFO  : Bisync successful
2025/05/09 03:31:58 NOTICE: (32)  : copy-listings dryrun
2025/05/09 03:31:58 NOTICE: (33)  : test sync without dry-run
2025/05/09 03:31:58 NOTICE: (34)  : bisync
2025/05/09 03:32:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:32:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:32:00 INFO  : Synching Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" with Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:32:00 INFO  : Building Path1 and Path2 listings
2025/05/09 03:32:01 INFO  : Path1 checking for diffs
2025/05/09 03:32:01 INFO  : - Path1    File changed: size (larger), time (newer) - file2.txt
2025/05/09 03:32:01 INFO  : - Path1    File was deleted          - file4.txt
2025/05/09 03:32:01 INFO  : - Path1    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:32:01 INFO  : - Path1    File was deleted          - file6.txt
2025/05/09 03:32:01 INFO  : - Path1    File changed: size (larger), time (newer) - file7.txt
2025/05/09 03:32:01 INFO  : - Path1    File is new               - file11.txt
2025/05/09 03:32:01 INFO  : Path1:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:32:01 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:32:01 INFO  : Path2 checking for diffs
2025/05/09 03:32:01 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/05/09 03:32:01 INFO  : - Path2    File was deleted          - file3.txt
2025/05/09 03:32:01 INFO  : - Path2    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:32:01 INFO  : - Path2    File changed: size (larger), time (newer) - file6.txt
2025/05/09 03:32:01 INFO  : - Path2    File was deleted          - file7.txt
2025/05/09 03:32:01 INFO  : - Path2    File is new               - file10.txt
2025/05/09 03:32:01 INFO  : Path2:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:32:01 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:32:01 INFO  : Applying changes
2025/05/09 03:32:01 INFO  : Checking potential conflicts...
2025/05/09 03:32:01 ERROR : file5.txt: md5 differ
2025/05/09 03:32:01 NOTICE: Local file system at /tmp/032943sa/dry_run/path2: 1 differences found
2025/05/09 03:32:01 NOTICE: Local file system at /tmp/032943sa/dry_run/path2: 1 errors while checking
2025/05/09 03:32:01 INFO  : Finished checking the potential conflicts. 1 differences found
2025/05/09 03:32:01 INFO  : - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file11.txt
2025/05/09 03:32:01 INFO  : - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file2.txt
2025/05/09 03:32:01 INFO  : - Path2    Queue delete              - /tmp/032943sa/dry_run/path2/file4.txt
2025/05/09 03:32:01 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/05/09 03:32:01 NOTICE: - Path1    Renaming Path1 copy                - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file5.txt.conflict1
2025/05/09 03:32:01 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict1
2025/05/09 03:32:01 NOTICE: - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file5.txt.conflict1
2025/05/09 03:32:01 NOTICE: - Path2    Renaming Path2 copy                - /tmp/032943sa/dry_run/path2/file5.txt.conflict2
2025/05/09 03:32:01 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict2
2025/05/09 03:32:01 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file5.txt.conflict2
2025/05/09 03:32:01 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file6.txt
2025/05/09 03:32:01 INFO  : - Path1    Queue copy to Path2       - /tmp/032943sa/dry_run/path2/file7.txt
2025/05/09 03:32:01 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file1.txt
2025/05/09 03:32:01 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file10.txt
2025/05/09 03:32:01 INFO  : - Path1    Queue delete              - TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/file3.txt
2025/05/09 03:32:01 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:32:03 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:32:04 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:04 INFO  : file10.txt: Copied (new)
2025/05/09 03:32:05 INFO  : file5.txt.conflict2: Copied (new)
2025/05/09 03:32:05 INFO  : file3.txt: Deleted
2025/05/09 03:32:05 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:32:05 INFO  : file11.txt: Copied (new)
2025/05/09 03:32:05 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:32:05 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:05 INFO  : file5.txt.conflict1: Copied (new)
2025/05/09 03:32:05 INFO  : file4.txt: Deleted
2025/05/09 03:32:05 INFO  : Updating listings
2025/05/09 03:32:05 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-rogitis9fana/032943sa/dry_run/path1/" vs Path2 "/tmp/032943sa/dry_run/path2/"
2025/05/09 03:32:05 INFO  : Bisync successful
    bisync_test.go:560: TEST dry_run PASSED
2025/05/09 03:32:06 DEBUG : OpenDrive root 'rclone-test-rogitis9fana': Purge remote
--- FAIL: TestBisyncRemoteLocal (144.41s)
    --- FAIL: TestBisyncRemoteLocal/createemptysrcdirs (95.45s)
    --- FAIL: TestBisyncRemoteLocal/dry_run (47.30s)
=== RUN   TestBisyncLocalRemote
2025/05/09 03:32:07 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa"
2025/05/09 03:32:07 DEBUG : Starting OpenDrive session with ID: 6b82a99cfbebd0bf3a722c835600a8a2f717ab4829277410a658386cd95a5b2c
2025/05/09 03:32:08 NOTICE: remote: TestOpenDrive:rclone-test-venehic8lupa
=== RUN   TestBisyncLocalRemote/compare_all
2025/05/09 03:32:08 DEBUG : Creating backend with remote "/tmp/033208pe"
2025/05/09 03:32:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/09 03:32:08 DEBUG : Creating backend with remote "/tmp/033208pe/compare_all/path1"
2025/05/09 03:32:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa/033208pe"
2025/05/09 03:32:08 DEBUG : Starting OpenDrive session with ID: 1b61352f6aa5ca7708f56cb6b238fdd7f4c7b2c1845d6195cddd25c7af6f5571
2025/05/09 03:32:08 INFO  : path2: Making directory
2025/05/09 03:32:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2"
2025/05/09 03:32:10 DEBUG : Starting OpenDrive session with ID: ca527ca09f75087b4273c52604fbaa9f1e7eee4a775015ac7507db28cd63459d
2025/05/09 03:32:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_compare_all/initial"
2025/05/09 03:32:11 DEBUG : Creating backend with remote "/tmp/033208pe/initdir/test_compare_all-jiyucad8"
2025/05/09 03:32:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/initdir/test_compare_all-jiyucad8: Waiting for checks to finish
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/initdir/test_compare_all-jiyucad8: Waiting for transfers to finish
2025/05/09 03:32:11 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:32:11 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:11 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:32:11 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:32:11 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:32:11 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:32:11 INFO  : file5.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:32:11 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:32:11 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/05/09 03:32:11 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:32:11 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:32:11 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_compare_all/modfiles"
2025/05/09 03:32:11 DEBUG : Creating backend with remote "/tmp/033208pe/datadir/test_compare_all-taqotiq7"
2025/05/09 03:32:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/datadir/test_compare_all-taqotiq7: Waiting for checks to finish
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/datadir/test_compare_all-taqotiq7: Waiting for transfers to finish
2025/05/09 03:32:11 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/05/09 03:32:11 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:32:11 DEBUG : file2.txt.f8ac1735.partial: renamed to: file2.txt
2025/05/09 03:32:11 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file10.txt.925c801f.partial: renamed to: file10.txt
2025/05/09 03:32:11 INFO  : file10.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/05/09 03:32:11 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/05/09 03:32:11 INFO  : file5R.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:32:11 DEBUG : file6.txt.925c801f.partial: renamed to: file6.txt
2025/05/09 03:32:11 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:32:11 DEBUG : file7.txt.925c801f.partial: renamed to: file7.txt
2025/05/09 03:32:11 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:32:11 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:32:11 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/05/09 03:32:11 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/05/09 03:32:11 DEBUG : file11.txt.925c801f.partial: renamed to: file11.txt
2025/05/09 03:32:11 INFO  : file11.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/05/09 03:32:11 INFO  : file5L.txt: Copied (new)
2025/05/09 03:32:11 ERROR : : error listing: directory not found
2025/05/09 03:32:11 ERROR : : error listing: directory not found
2025/05/09 03:32:11 NOTICE: checking initFs Local file system at /tmp/033208pe/initdir/test_compare_all-jiyucad8
2025/05/09 03:32:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/compare_all/path1: Waiting for checks to finish
2025/05/09 03:32:11 DEBUG : Local file system at /tmp/033208pe/compare_all/path1: Waiting for transfers to finish
2025/05/09 03:32:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:11 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:32:11 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:32:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:32:11 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:32:11 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:32:11 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:32:11 INFO  : file5.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:32:11 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:32:11 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:11 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/05/09 03:32:11 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:11 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:32:11 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:11 NOTICE: checking Path1 Local file system at /tmp/033208pe/compare_all/path1
2025/05/09 03:32:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:32:12 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/compare_all/path2': Waiting for checks to finish
2025/05/09 03:32:12 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/compare_all/path2': Waiting for transfers to finish
2025/05/09 03:32:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:14 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:14 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:14 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:14 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:15 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:15 INFO  : file5.txt: Copied (new)
2025/05/09 03:32:16 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:16 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:16 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:16 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:16 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:16 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:17 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:32:17 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:17 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:18 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:18 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:32:18 NOTICE: checking path2 OpenDrive root 'rclone-test-venehic8lupa/033208pe/compare_all/path2'
2025/05/09 03:32:18 NOTICE: (01)  : test changes compare-all
2025/05/09 03:32:18 NOTICE: (02)  : test initial bisync
2025/05/09 03:32:18 NOTICE: (03)  : bisync resync compare-all
2025/05/09 03:32:20 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:32:20 INFO  : Synching Path1 "/tmp/033208pe/compare_all/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/"
2025/05/09 03:32:20 INFO  : Copying Path2 files to Path1
2025/05/09 03:32:20 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:32:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:32:20 INFO  : Resync updating listings
2025/05/09 03:32:20 INFO  : Validating listings for Path1 "/tmp/033208pe/compare_all/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/"
2025/05/09 03:32:20 INFO  : Bisync successful
2025/05/09 03:32:20 NOTICE: (04)  : test make modifications on both paths
2025/05/09 03:32:20 NOTICE: (05)  : test new on path2 - file10
2025/05/09 03:32:20 NOTICE: (06)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file10.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/
2025/05/09 03:32:23 INFO  : file10.txt: Copied (new)
2025/05/09 03:32:23 NOTICE: (07)  : test newer on path2 - file1
2025/05/09 03:32:23 NOTICE: (08)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file1.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/
2025/05/09 03:32:26 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:32:26 NOTICE: (09)  : test new on path1 - file11
2025/05/09 03:32:26 NOTICE: (10)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file11.txt /tmp/033208pe/compare_all/path1/
2025/05/09 03:32:26 INFO  : file11.txt: Copied (new)
2025/05/09 03:32:26 NOTICE: (11)  : test newer on path1 - file2
2025/05/09 03:32:26 NOTICE: (12)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file2.txt /tmp/033208pe/compare_all/path1/
2025/05/09 03:32:26 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:32:26 NOTICE: (13)  : test deleted on path2 - file3
2025/05/09 03:32:26 NOTICE: (14)  : delete-file TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file3.txt
2025/05/09 03:32:27 INFO  : file3.txt: Deleted
2025/05/09 03:32:27 NOTICE: (15)  : test deleted on path1 - file4
2025/05/09 03:32:27 NOTICE: (16)  : delete-file /tmp/033208pe/compare_all/path1/file4.txt
2025/05/09 03:32:27 INFO  : file4.txt: Deleted
2025/05/09 03:32:27 NOTICE: (17)  : test deleted on both paths - file8
2025/05/09 03:32:27 NOTICE: (18)  : delete-file /tmp/033208pe/compare_all/path1/file8.txt
2025/05/09 03:32:27 INFO  : file8.txt: Deleted
2025/05/09 03:32:27 NOTICE: (19)  : delete-file TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file8.txt
2025/05/09 03:32:28 INFO  : file8.txt: Deleted
2025/05/09 03:32:28 NOTICE: (20)  : test changed on both paths - file5 (file5R, file5L)
2025/05/09 03:32:28 NOTICE: (21)  : touch-glob 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/ file5R.txt
2025/05/09 03:32:28 NOTICE: (22)  : copy-as /tmp/033208pe/datadir/test_compare_all-taqotiq7/file5R.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/ file5.txt
2025/05/09 03:32:30 INFO  : file5R.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:32:30 NOTICE: (23)  : touch-glob 2001-03-04 /tmp/033208pe/datadir/test_compare_all-taqotiq7/ file5L.txt
2025/05/09 03:32:30 NOTICE: (24)  : copy-as /tmp/033208pe/datadir/test_compare_all-taqotiq7/file5L.txt /tmp/033208pe/compare_all/path1/ file5.txt
2025/05/09 03:32:30 INFO  : file5L.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:32:30 NOTICE: (25)  : test newer on path2 and deleted on path1 - file6
2025/05/09 03:32:30 NOTICE: (26)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file6.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/
2025/05/09 03:32:32 INFO  : file6.txt: Copied (replaced existing)
2025/05/09 03:32:32 NOTICE: (27)  : delete-file /tmp/033208pe/compare_all/path1/file6.txt
2025/05/09 03:32:32 INFO  : file6.txt: Deleted
2025/05/09 03:32:32 NOTICE: (28)  : test newer on path1 and deleted on path2 - file7
2025/05/09 03:32:32 NOTICE: (29)  : touch-copy 2001-01-02 /tmp/033208pe/datadir/test_compare_all-taqotiq7/file7.txt /tmp/033208pe/compare_all/path1/
2025/05/09 03:32:32 INFO  : file7.txt: Copied (replaced existing)
2025/05/09 03:32:32 NOTICE: (30)  : delete-file TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file7.txt
2025/05/09 03:32:33 INFO  : file7.txt: Deleted
2025/05/09 03:32:33 NOTICE: (31)  : test bisync run
2025/05/09 03:32:33 NOTICE: (32)  : bisync compare-all
2025/05/09 03:32:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:32:35 INFO  : Synching Path1 "/tmp/033208pe/compare_all/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/"
2025/05/09 03:32:35 INFO  : Building Path1 and Path2 listings
2025/05/09 03:32:35 INFO  : Path1 checking for diffs
2025/05/09 03:32:35 INFO  : - Path1    File changed: size (larger), time (newer), hash - file2.txt
2025/05/09 03:32:35 INFO  : - Path1    File was deleted          - file4.txt
2025/05/09 03:32:35 INFO  : - Path1    File changed: size (larger), time (newer), hash - file5.txt
2025/05/09 03:32:35 INFO  : - Path1    File was deleted          - file6.txt
2025/05/09 03:32:35 INFO  : - Path1    File changed: size (larger), time (newer), hash - file7.txt
2025/05/09 03:32:35 INFO  : - Path1    File was deleted          - file8.txt
2025/05/09 03:32:35 INFO  : - Path1    File is new               - file11.txt
2025/05/09 03:32:35 INFO  : Path1:    7 changes:    1 new,    3 modified,    3 deleted
2025/05/09 03:32:35 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller,    3 hash differs)
2025/05/09 03:32:35 INFO  : Path2 checking for diffs
2025/05/09 03:32:35 INFO  : - Path2    File changed: size (larger), time (newer), hash - file1.txt
2025/05/09 03:32:35 INFO  : - Path2    File was deleted          - file3.txt
2025/05/09 03:32:35 INFO  : - Path2    File changed: size (larger), time (newer), hash - file5.txt
2025/05/09 03:32:35 INFO  : - Path2    File changed: size (larger), time (newer), hash - file6.txt
2025/05/09 03:32:35 INFO  : - Path2    File was deleted          - file7.txt
2025/05/09 03:32:35 INFO  : - Path2    File was deleted          - file8.txt
2025/05/09 03:32:35 INFO  : - Path2    File is new               - file10.txt
2025/05/09 03:32:35 INFO  : Path2:    7 changes:    1 new,    3 modified,    3 deleted
2025/05/09 03:32:35 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller,    3 hash differs)
2025/05/09 03:32:35 INFO  : Applying changes
2025/05/09 03:32:35 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file11.txt
2025/05/09 03:32:35 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file2.txt
2025/05/09 03:32:35 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file4.txt
2025/05/09 03:32:35 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/05/09 03:32:35 NOTICE: - Path1    Renaming Path1 copy                - /tmp/033208pe/compare_all/path1/file5.txt.conflict1
2025/05/09 03:32:35 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict1
2025/05/09 03:32:35 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file5.txt.conflict1
2025/05/09 03:32:35 NOTICE: - Path2    Renaming Path2 copy                - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file5.txt.conflict2
2025/05/09 03:32:36 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict2
2025/05/09 03:32:36 NOTICE: - Path2    Queue copy to Path1       - /tmp/033208pe/compare_all/path1/file5.txt.conflict2
2025/05/09 03:32:36 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/compare_all/path1/file6.txt
2025/05/09 03:32:36 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/file7.txt
2025/05/09 03:32:36 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/compare_all/path1/file1.txt
2025/05/09 03:32:36 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/compare_all/path1/file10.txt
2025/05/09 03:32:36 INFO  : - Path1    Queue delete              - /tmp/033208pe/compare_all/path1/file3.txt
2025/05/09 03:32:36 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:32:36 INFO  : file10.txt: Copied (new)
2025/05/09 03:32:36 INFO  : file5.txt.conflict2: Copied (new)
2025/05/09 03:32:36 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:36 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:32:36 INFO  : file3.txt: Deleted
2025/05/09 03:32:36 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:32:38 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:32:38 INFO  : file11.txt: Copied (new)
2025/05/09 03:32:38 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:38 INFO  : file5.txt.conflict1: Copied (new)
2025/05/09 03:32:39 INFO  : file4.txt: Deleted
2025/05/09 03:32:39 INFO  : Updating listings
2025/05/09 03:32:39 INFO  : Validating listings for Path1 "/tmp/033208pe/compare_all/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/compare_all/path2/"
2025/05/09 03:32:39 INFO  : Bisync successful
    bisync_test.go:560: TEST compare_all PASSED
=== RUN   TestBisyncLocalRemote/max_delete_path1
2025/05/09 03:32:39 DEBUG : Creating backend with remote "/tmp/033208pe/max_delete_path1/path1"
2025/05/09 03:32:39 INFO  : path2: Making directory
2025/05/09 03:32:39 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2"
2025/05/09 03:32:40 DEBUG : Starting OpenDrive session with ID: 576f2c295a7da603006e4292aa1d76155294835f35ff688390f3dd19e63c9c5a
2025/05/09 03:32:40 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/initial"
2025/05/09 03:32:40 DEBUG : Creating backend with remote "/tmp/033208pe/initdir/test_max_delete_path1-sisaxih3"
2025/05/09 03:32:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:40 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/initdir/test_max_delete_path1-sisaxih3: Waiting for checks to finish
2025/05/09 03:32:41 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/initdir/test_max_delete_path1-sisaxih3: Waiting for transfers to finish
2025/05/09 03:32:41 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:32:41 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:32:41 INFO  : file5.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:32:41 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:32:41 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/05/09 03:32:41 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:32:41 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:41 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:32:41 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:32:41 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file9.txt.cf9a93ac.partial: renamed to: file9.txt
2025/05/09 03:32:41 INFO  : file9.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/modfiles"
2025/05/09 03:32:41 DEBUG : Creating backend with remote "/tmp/033208pe/datadir/test_max_delete_path1-lewotaf0"
2025/05/09 03:32:41 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/datadir/test_max_delete_path1-lewotaf0: Waiting for checks to finish
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/datadir/test_max_delete_path1-lewotaf0: Waiting for transfers to finish
2025/05/09 03:32:41 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/05/09 03:32:41 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/05/09 03:32:41 INFO  : hold.txt: Copied (new)
2025/05/09 03:32:41 ERROR : : error listing: directory not found
2025/05/09 03:32:41 ERROR : : error listing: directory not found
2025/05/09 03:32:41 NOTICE: checking initFs Local file system at /tmp/033208pe/initdir/test_max_delete_path1-sisaxih3
2025/05/09 03:32:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/max_delete_path1/path1: Waiting for checks to finish
2025/05/09 03:32:41 DEBUG : Local file system at /tmp/033208pe/max_delete_path1/path1: Waiting for transfers to finish
2025/05/09 03:32:41 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:32:41 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:32:41 INFO  : file5.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:32:41 INFO  : file2.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:32:41 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:32:41 INFO  : file1.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:32:41 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/05/09 03:32:41 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:41 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:32:41 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:41 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:41 DEBUG : file9.txt.cf9a93ac.partial: renamed to: file9.txt
2025/05/09 03:32:41 INFO  : file9.txt: Copied (new)
2025/05/09 03:32:41 NOTICE: checking Path1 Local file system at /tmp/033208pe/max_delete_path1/path1
2025/05/09 03:32:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/05/09 03:32:41 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/max_delete_path1/path2': Waiting for checks to finish
2025/05/09 03:32:41 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/max_delete_path1/path2': Waiting for transfers to finish
2025/05/09 03:32:42 ERROR : file1.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404)
2025/05/09 03:32:42 ERROR : file5.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404)
2025/05/09 03:32:42 ERROR : file2.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404)
2025/05/09 03:32:44 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:44 INFO  : file7.txt: Copied (new)
2025/05/09 03:32:44 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:44 INFO  : file4.txt: Copied (new)
2025/05/09 03:32:44 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:44 INFO  : file6.txt: Copied (new)
2025/05/09 03:32:44 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:44 INFO  : file3.txt: Copied (new)
2025/05/09 03:32:45 DEBUG : file9.txt: Uploading chunk 0, size=109, remain=0
2025/05/09 03:32:46 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:32:46 INFO  : file8.txt: Copied (new)
2025/05/09 03:32:46 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:32:46 INFO  : file9.txt: Copied (new)
    bisync_test.go:1943: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1943
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:461
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Received unexpected error:
        	            	failed to get folder list: Directory doesn't exist (Error 404)
        	Test:       	TestBisyncLocalRemote/max_delete_path1
        	Messages:   	setting up path2
2025/05/09 03:32:46 NOTICE: checking path2 OpenDrive root 'rclone-test-venehic8lupa/033208pe/max_delete_path1/path2'
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:463
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Should be true
        	Test:       	TestBisyncLocalRemote/max_delete_path1
        	Messages:   	listing wrong, want
        	            	  file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109) got
        	            	  file3.txt (0), file4.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109)
    fstest.go:197: Not found "file2.txt"
    fstest.go:197: Not found "file1.txt"
    fstest.go:197: Not found "file5.txt"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:463
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 3
        	Test:       	TestBisyncLocalRemote/max_delete_path1
        	Messages:   	3 objects not found
2025/05/09 03:32:55 NOTICE: (01)  : test max-delete-path1
2025/05/09 03:32:55 NOTICE: (02)  : test initial bisync
2025/05/09 03:32:55 NOTICE: (03)  : bisync resync
2025/05/09 03:32:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:32:58 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:32:58 INFO  : Synching Path1 "/tmp/033208pe/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/"
2025/05/09 03:32:58 INFO  : Copying Path2 files to Path1
2025/05/09 03:32:58 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:32:58 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:32:59 INFO  : file1.txt: Copied (new)
2025/05/09 03:33:00 INFO  : file5.txt: Copied (new)
2025/05/09 03:33:00 INFO  : file2.txt: Copied (new)
2025/05/09 03:33:00 INFO  : Resync updating listings
2025/05/09 03:33:00 INFO  : Validating listings for Path1 "/tmp/033208pe/max_delete_path1/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/"
2025/05/09 03:33:00 INFO  : Bisync successful
2025/05/09 03:33:00 NOTICE: (04)  : test delete >50% of local files
2025/05/09 03:33:00 NOTICE: (05)  : delete-file /tmp/033208pe/max_delete_path1/path1/file1.txt
2025/05/09 03:33:00 INFO  : file1.txt: Deleted
2025/05/09 03:33:00 NOTICE: (06)  : delete-file /tmp/033208pe/max_delete_path1/path1/file2.txt
2025/05/09 03:33:00 INFO  : file2.txt: Deleted
2025/05/09 03:33:00 NOTICE: (07)  : delete-file /tmp/033208pe/max_delete_path1/path1/file3.txt
2025/05/09 03:33:00 INFO  : file3.txt: Deleted
2025/05/09 03:33:00 NOTICE: (08)  : delete-file /tmp/033208pe/max_delete_path1/path1/file4.txt
2025/05/09 03:33:00 INFO  : file4.txt: Deleted
2025/05/09 03:33:00 NOTICE: (09)  : delete-file /tmp/033208pe/max_delete_path1/path1/file5.txt
2025/05/09 03:33:00 INFO  : file5.txt: Deleted
2025/05/09 03:33:00 NOTICE: (10)  : test sync should fail due to too many local deletes
2025/05/09 03:33:00 NOTICE: (11)  : bisync
2025/05/09 03:33:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:33:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:33:03 INFO  : Synching Path1 "/tmp/033208pe/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/"
2025/05/09 03:33:03 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:03 INFO  : Path1 checking for diffs
2025/05/09 03:33:03 INFO  : - Path1    File was deleted          - file1.txt
2025/05/09 03:33:03 INFO  : - Path1    File was deleted          - file2.txt
2025/05/09 03:33:03 INFO  : - Path1    File was deleted          - file3.txt
2025/05/09 03:33:03 INFO  : - Path1    File was deleted          - file4.txt
2025/05/09 03:33:03 INFO  : - Path1    File was deleted          - file5.txt
2025/05/09 03:33:03 INFO  : Path1:    5 changes:    0 new,    0 modified,    5 deleted
2025/05/09 03:33:03 INFO  : Path2 checking for diffs
2025/05/09 03:33:03 INFO  : - Path2    File is new               - modtime_write_test
2025/05/09 03:33:03 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2025/05/09 03:33:03 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path1 "/tmp/033208pe/max_delete_path1/path1/". Run with --force if desired.
2025/05/09 03:33:03 NOTICE: Bisync aborted. Please try again.
2025/05/09 03:33:03 NOTICE: Bisync error: too many deletes
2025/05/09 03:33:03 NOTICE: (12)  : copy-listings initial-fail
2025/05/09 03:33:03 NOTICE: (13)  : test change max-delete limit to 60%. sync should run.
2025/05/09 03:33:03 NOTICE: (14)  : bisync max-delete=60
2025/05/09 03:33:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:33:05 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:33:05 INFO  : Synching Path1 "/tmp/033208pe/max_delete_path1/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/"
2025/05/09 03:33:05 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:05 INFO  : Path1 checking for diffs
2025/05/09 03:33:05 INFO  : - Path1    File was deleted          - file1.txt
2025/05/09 03:33:05 INFO  : - Path1    File was deleted          - file2.txt
2025/05/09 03:33:05 INFO  : - Path1    File was deleted          - file3.txt
2025/05/09 03:33:05 INFO  : - Path1    File was deleted          - file4.txt
2025/05/09 03:33:05 INFO  : - Path1    File was deleted          - file5.txt
2025/05/09 03:33:05 INFO  : Path1:    5 changes:    0 new,    0 modified,    5 deleted
2025/05/09 03:33:05 INFO  : Path2 checking for diffs
2025/05/09 03:33:05 INFO  : Applying changes
2025/05/09 03:33:05 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/file1.txt
2025/05/09 03:33:05 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/file2.txt
2025/05/09 03:33:05 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/file3.txt
2025/05/09 03:33:05 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/file4.txt
2025/05/09 03:33:05 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/file5.txt
2025/05/09 03:33:05 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:33:05 INFO  : file2.txt: Deleted
2025/05/09 03:33:05 INFO  : file1.txt: Deleted
2025/05/09 03:33:05 INFO  : file4.txt: Deleted
2025/05/09 03:33:06 INFO  : file5.txt: Deleted
2025/05/09 03:33:07 INFO  : file3.txt: Deleted
2025/05/09 03:33:07 INFO  : Updating listings
2025/05/09 03:33:07 INFO  : Validating listings for Path1 "/tmp/033208pe/max_delete_path1/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/max_delete_path1/path2/"
2025/05/09 03:33:07 INFO  : Bisync successful
2025/05/09 03:33:07 NOTICE: ----------------------------------------------------------
2025/05/09 03:33:07 NOTICE: | MISCOMPARE  -Golden vs +Results for  initial-fail._testdir_path1.._testdir_path2.path2.lst-new
2025/05/09 03:33:07 NOTICE: | @@ -18,0 +19,2 @@
2025/05/09 03:33:07 NOTICE: | +-       18 - - 2000-01-01 00:00:00 +0000 UTC "\"modtime_write_test\""
2025/05/09 03:33:07 NOTICE: | +
2025/05/09 03:33:07 NOTICE: ----------------------------------------------------------
2025/05/09 03:33:07 NOTICE: | MISCOMPARE  -Golden vs +Results for  test.log
2025/05/09 03:33:07 NOTICE: | @@ -48,0 +49,2 @@
2025/05/09 03:33:07 NOTICE: | +INFO  : - Path2    File is new               - modtime_write_test
2025/05/09 03:33:07 NOTICE: | +INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2025/05/09 03:33:07 NOTICE: ----------------------------------------------------------
    bisync_test.go:560: TEST max_delete_path1 FAILED - 2 MISCOMPARED FILES
=== RUN   TestBisyncLocalRemote/rclone_args
2025/05/09 03:33:07 DEBUG : Creating backend with remote "/tmp/033208pe/rclone_args/path1"
2025/05/09 03:33:07 INFO  : path2: Making directory
2025/05/09 03:33:07 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2"
2025/05/09 03:33:07 DEBUG : Starting OpenDrive session with ID: 171d41b7df5885ae77f8dd1c5e48400c4e2cfc6736b587e3c9863dbdb25225b6
2025/05/09 03:33:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/initial"
2025/05/09 03:33:09 DEBUG : Creating backend with remote "/tmp/033208pe/initdir/test_rclone_args-wowedak9"
2025/05/09 03:33:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : subdir: Making directory with metadata
2025/05/09 03:33:09 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/05/09 03:33:09 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/05/09 03:33:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/initdir/test_rclone_args-wowedak9: Waiting for checks to finish
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/initdir/test_rclone_args-wowedak9: Waiting for transfers to finish
2025/05/09 03:33:09 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:33:09 INFO  : file2.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/05/09 03:33:09 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : subdir/file21.txt.5028a97.partial: renamed to: subdir/file21.txt
2025/05/09 03:33:09 INFO  : subdir/file21.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/05/09 03:33:09 DEBUG : file1.txt.dae86b42.partial: renamed to: file1.txt
2025/05/09 03:33:09 INFO  : file1.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:33:09 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:33:09 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:33:09 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/modfiles"
2025/05/09 03:33:09 DEBUG : Creating backend with remote "/tmp/033208pe/datadir/test_rclone_args-woyupos1"
2025/05/09 03:33:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file21.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/datadir/test_rclone_args-woyupos1: Waiting for checks to finish
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/datadir/test_rclone_args-woyupos1: Waiting for transfers to finish
2025/05/09 03:33:09 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : file20.txt.b4075ff1.partial: renamed to: file20.txt
2025/05/09 03:33:09 INFO  : file20.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : file21.txt.b4075ff1.partial: renamed to: file21.txt
2025/05/09 03:33:09 INFO  : file21.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:33:09 DEBUG : file2.txt.6bedbe24.partial: renamed to: file2.txt
2025/05/09 03:33:09 INFO  : file2.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:33:09 DEBUG : file1.txt.6bedbe24.partial: renamed to: file1.txt
2025/05/09 03:33:09 INFO  : file1.txt: Copied (new)
2025/05/09 03:33:09 ERROR : : error listing: directory not found
2025/05/09 03:33:09 ERROR : : error listing: directory not found
2025/05/09 03:33:09 NOTICE: checking initFs Local file system at /tmp/033208pe/initdir/test_rclone_args-wowedak9
2025/05/09 03:33:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : subdir: Making directory with metadata
2025/05/09 03:33:09 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/05/09 03:33:09 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/05/09 03:33:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/rclone_args/path1: Waiting for checks to finish
2025/05/09 03:33:09 DEBUG : Local file system at /tmp/033208pe/rclone_args/path1: Waiting for transfers to finish
2025/05/09 03:33:09 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:33:09 INFO  : file2.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/05/09 03:33:09 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:33:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:33:09 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:09 DEBUG : subdir/file21.txt.5028a97.partial: renamed to: subdir/file21.txt
2025/05/09 03:33:09 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:33:09 INFO  : subdir/file21.txt: Copied (new)
2025/05/09 03:33:09 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:33:09 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/05/09 03:33:09 DEBUG : file1.txt.dae86b42.partial: renamed to: file1.txt
2025/05/09 03:33:09 INFO  : file1.txt: Copied (new)
2025/05/09 03:33:09 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:09 NOTICE: checking Path1 Local file system at /tmp/033208pe/rclone_args/path1
2025/05/09 03:33:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:33:09 INFO  : subdir: Making directory
2025/05/09 03:33:10 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:10 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/05/09 03:33:10 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/rclone_args/path2': Waiting for checks to finish
2025/05/09 03:33:10 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/rclone_args/path2': Waiting for transfers to finish
2025/05/09 03:33:11 DEBUG : file1.txt: Uploading chunk 0, size=19, remain=0
2025/05/09 03:33:11 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:33:12 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/05/09 03:33:12 INFO  : file1.txt: Copied (new)
2025/05/09 03:33:12 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:12 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:33:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:12 INFO  : file2.txt: Copied (new)
2025/05/09 03:33:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:33:12 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:33:13 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:33:13 INFO  : subdir/file21.txt: Copied (new)
2025/05/09 03:33:13 NOTICE: checking path2 OpenDrive root 'rclone-test-venehic8lupa/033208pe/rclone_args/path2'
2025/05/09 03:33:14 NOTICE: (01)  : test rclone-args
2025/05/09 03:33:14 NOTICE: (02)  : touch-glob 2001-01-02 /tmp/033208pe/datadir/test_rclone_args-woyupos1/ *
2025/05/09 03:33:14 NOTICE: (03)  : test initial bisync
2025/05/09 03:33:14 NOTICE: (04)  : bisync resync checksum
2025/05/09 03:33:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:33:16 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:16 INFO  : Copying Path2 files to Path1
2025/05/09 03:33:16 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:33:16 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:33:16 INFO  : Resync updating listings
2025/05/09 03:33:16 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:16 INFO  : Bisync successful
2025/05/09 03:33:16 NOTICE: (05)  : test place newer files on both paths
2025/05/09 03:33:16 NOTICE: (06)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file1.txt /tmp/033208pe/rclone_args/path1/
2025/05/09 03:33:16 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:33:16 NOTICE: (07)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file2.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/
2025/05/09 03:33:19 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:33:19 NOTICE: (08)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file20.txt /tmp/033208pe/rclone_args/path1/subdir
2025/05/09 03:33:19 INFO  : file20.txt: Updated modification time in destination
2025/05/09 03:33:19 NOTICE: (09)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file21.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir
2025/05/09 03:33:21 INFO  : file21.txt: Copied (replaced existing)
2025/05/09 03:33:21 NOTICE: (10)  : touch-glob 2001-01-02 /tmp/033208pe/rclone_args/path1/ file1.txt
2025/05/09 03:33:21 NOTICE: (11)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file2.txt
2025/05/09 03:33:23 NOTICE: (12)  : touch-glob 2001-01-02 /tmp/033208pe/rclone_args/path1/subdir/ file20.txt
2025/05/09 03:33:23 NOTICE: (13)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir/ file21.txt
2025/05/09 03:33:24 NOTICE: (14)  : test run bisync with custom options
2025/05/09 03:33:24 NOTICE: (15)  : bisync checksum
2025/05/09 03:33:27 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:27 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:33:27 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:27 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:27 INFO  : Path1 checking for diffs
2025/05/09 03:33:27 INFO  : - Path1    File changed: hash - file1.txt
2025/05/09 03:33:27 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/05/09 03:33:27 INFO  : (Modified:    1 hash differs)
2025/05/09 03:33:27 INFO  : Path2 checking for diffs
2025/05/09 03:33:27 INFO  : - Path2    File changed: size (larger), hash - file2.txt
2025/05/09 03:33:27 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/05/09 03:33:27 INFO  : (Modified:    1 larger,    0 smaller,    1 hash differs)
2025/05/09 03:33:27 INFO  : Applying changes
2025/05/09 03:33:27 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/file1.txt
2025/05/09 03:33:27 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/file2.txt
2025/05/09 03:33:27 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:33:27 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:33:27 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:33:29 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:33:29 INFO  : Updating listings
2025/05/09 03:33:29 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:29 INFO  : Bisync successful
2025/05/09 03:33:29 NOTICE: (16)  : touch-glob 2007-07-23 /tmp/033208pe/datadir/test_rclone_args-woyupos1/ *
2025/05/09 03:33:29 NOTICE: (17)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file1.txt /tmp/033208pe/rclone_args/path1/
2025/05/09 03:33:29 INFO  : file1.txt: Updated modification time in destination
2025/05/09 03:33:29 NOTICE: (18)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file2.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/
2025/05/09 03:33:31 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:33:31 NOTICE: (19)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file20.txt /tmp/033208pe/rclone_args/path1/subdir
2025/05/09 03:33:31 INFO  : file20.txt: Updated modification time in destination
2025/05/09 03:33:31 NOTICE: (20)  : copy-as /tmp/033208pe/datadir/test_rclone_args-woyupos1/file21.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file1.txt
2025/05/09 03:33:33 INFO  : file21.txt: Copied (replaced existing) to: file1.txt
2025/05/09 03:33:33 NOTICE: (21)  : touch-glob 2007-07-23 /tmp/033208pe/rclone_args/path1/ file1.txt
2025/05/09 03:33:33 NOTICE: (22)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file2.txt
2025/05/09 03:33:34 NOTICE: (23)  : touch-glob 2007-07-23 /tmp/033208pe/rclone_args/path1/subdir/ file20.txt
2025/05/09 03:33:34 NOTICE: (24)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir/ file21.txt
2025/05/09 03:33:34 NOTICE: (25)  : bisync size-only
2025/05/09 03:33:36 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:33:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:33:36 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:36 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:36 INFO  : Path1 checking for diffs
2025/05/09 03:33:36 INFO  : Path2 checking for diffs
2025/05/09 03:33:36 INFO  : - Path2    File changed: size (smaller) - file1.txt
2025/05/09 03:33:36 INFO  : - Path2    File is new               - modtime_write_test
2025/05/09 03:33:36 INFO  : Path2:    2 changes:    1 new,    1 modified,    0 deleted
2025/05/09 03:33:36 INFO  : (Modified:    0 larger,    1 smaller)
2025/05/09 03:33:36 INFO  : Applying changes
2025/05/09 03:33:36 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/file1.txt
2025/05/09 03:33:36 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/modtime_write_test
2025/05/09 03:33:36 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:33:37 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:33:37 INFO  : Updating listings
2025/05/09 03:33:37 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:37 INFO  : Bisync successful
2025/05/09 03:33:37 NOTICE: (26)  : bisync resync
2025/05/09 03:33:39 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:33:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:33:39 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:39 INFO  : Copying Path2 files to Path1
2025/05/09 03:33:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:33:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:33:40 INFO  : file2.txt: Updated modification time in destination
2025/05/09 03:33:40 INFO  : subdir/file21.txt: Updated modification time in destination
2025/05/09 03:33:40 INFO  : subdir/file20.txt: Updated modification time in destination
2025/05/09 03:33:40 INFO  : Resync updating listings
2025/05/09 03:33:40 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:40 INFO  : Bisync successful
2025/05/09 03:33:40 NOTICE: (27)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file1.txt /tmp/033208pe/rclone_args/path1/
2025/05/09 03:33:40 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:33:40 NOTICE: (28)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file2.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/
2025/05/09 03:33:42 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:33:42 NOTICE: (29)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file20.txt /tmp/033208pe/rclone_args/path1/subdir
2025/05/09 03:33:42 NOTICE: (30)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file21.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir
2025/05/09 03:33:45 INFO  : file21.txt: Copied (replaced existing)
2025/05/09 03:33:45 NOTICE: (31)  : touch-glob 2007-07-23 /tmp/033208pe/rclone_args/path1/ file1.txt
2025/05/09 03:33:45 NOTICE: (32)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file2.txt
2025/05/09 03:33:45 NOTICE: (33)  : touch-glob 2007-07-23 /tmp/033208pe/rclone_args/path1/subdir/ file20.txt
2025/05/09 03:33:45 NOTICE: (34)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir/ file21.txt
2025/05/09 03:33:46 NOTICE: (35)  : bisync ignore-size
2025/05/09 03:33:47 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:47 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:33:47 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": false,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:33:47 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:47 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:48 INFO  : Path1 checking for diffs
2025/05/09 03:33:48 INFO  : Path2 checking for diffs
2025/05/09 03:33:48 INFO  : - Path2    File changed: time (newer) - file2.txt
2025/05/09 03:33:48 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/05/09 03:33:48 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/05/09 03:33:48 INFO  : (Modified:    2 newer,    0 older)
2025/05/09 03:33:48 INFO  : Applying changes
2025/05/09 03:33:48 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/file2.txt
2025/05/09 03:33:48 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/subdir/file21.txt
2025/05/09 03:33:48 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:33:48 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:48 INFO  : file2.txt: Updated modification time in destination
2025/05/09 03:33:48 INFO  : subdir/file21.txt: Updated modification time in destination
2025/05/09 03:33:48 INFO  : Updating listings
2025/05/09 03:33:48 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:48 INFO  : Bisync successful
2025/05/09 03:33:48 NOTICE: (36)  : bisync resync compare-all
2025/05/09 03:33:50 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:33:50 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:50 INFO  : Copying Path2 files to Path1
2025/05/09 03:33:50 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:33:51 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:33:52 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:33:52 INFO  : Resync updating listings
2025/05/09 03:33:52 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:52 INFO  : Bisync successful
2025/05/09 03:33:52 NOTICE: (37)  : copy-as /tmp/033208pe/datadir/test_rclone_args-woyupos1/file21.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file2.txt
2025/05/09 03:33:54 INFO  : file21.txt: Copied (replaced existing) to: file2.txt
2025/05/09 03:33:54 NOTICE: (38)  : touch-glob 2023-08-26 /tmp/033208pe/datadir/test_rclone_args-woyupos1/ *
2025/05/09 03:33:54 NOTICE: (39)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file1.txt /tmp/033208pe/rclone_args/path1/
2025/05/09 03:33:54 INFO  : file1.txt: Updated modification time in destination
2025/05/09 03:33:54 NOTICE: (40)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file20.txt /tmp/033208pe/rclone_args/path1/subdir
2025/05/09 03:33:54 INFO  : file20.txt: Updated modification time in destination
2025/05/09 03:33:54 NOTICE: (41)  : copy-file /tmp/033208pe/datadir/test_rclone_args-woyupos1/file21.txt TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir
2025/05/09 03:33:56 INFO  : file21.txt: Copied (replaced existing)
2025/05/09 03:33:56 NOTICE: (42)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/ file2.txt
2025/05/09 03:33:57 NOTICE: (43)  : touch-glob 2023-08-26 /tmp/033208pe/rclone_args/path1/ file1.txt
2025/05/09 03:33:57 NOTICE: (44)  : touch-glob 2023-08-26 /tmp/033208pe/rclone_args/path1/subdir/ file20.txt
2025/05/09 03:33:57 NOTICE: (45)  : touch-glob 2023-08-26 TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir/ file21.txt
2025/05/09 03:33:57 NOTICE: (46)  : bisync compare-all
2025/05/09 03:33:59 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:33:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2025/05/09 03:33:59 INFO  : Synching Path1 "/tmp/033208pe/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:33:59 INFO  : Building Path1 and Path2 listings
2025/05/09 03:33:59 INFO  : Path1 checking for diffs
2025/05/09 03:33:59 INFO  : - Path1    File changed: time (newer) - file1.txt
2025/05/09 03:33:59 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2025/05/09 03:33:59 INFO  : Path1:    2 changes:    0 new,    2 modified,    0 deleted
2025/05/09 03:33:59 INFO  : (Modified:    2 newer,    0 older)
2025/05/09 03:33:59 INFO  : Path2 checking for diffs
2025/05/09 03:33:59 INFO  : - Path2    File changed: size (smaller), hash - file2.txt
2025/05/09 03:33:59 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/05/09 03:33:59 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/05/09 03:33:59 INFO  : (Modified:    1 newer,    0 older,    0 larger,    1 smaller,    1 hash differs)
2025/05/09 03:33:59 INFO  : Applying changes
2025/05/09 03:33:59 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/file1.txt
2025/05/09 03:33:59 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/subdir/file20.txt
2025/05/09 03:33:59 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/file2.txt
2025/05/09 03:33:59 INFO  : - Path2    Queue copy to Path1       - /tmp/033208pe/rclone_args/path1/subdir/file21.txt
2025/05/09 03:33:59 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:34:00 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:00 INFO  : subdir/file21.txt: Updated modification time in destination
2025/05/09 03:34:00 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:34:00 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:34:00 INFO  : file1.txt: Updated modification time in destination
2025/05/09 03:34:00 INFO  : subdir/file20.txt: Updated modification time in destination
2025/05/09 03:34:00 INFO  : Updating listings
2025/05/09 03:34:00 INFO  : Validating listings for Path1 "/tmp/033208pe/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rclone_args/path2/"
2025/05/09 03:34:00 INFO  : Bisync successful
2025/05/09 03:34:00 NOTICE: ----------------------------------------------------------
2025/05/09 03:34:00 NOTICE: | MISCOMPARE  -Golden vs +Results for  test.log
2025/05/09 03:34:00 NOTICE: | @@ -84 +84,2 @@
2025/05/09 03:34:00 NOTICE: | -INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/05/09 03:34:00 NOTICE: | +INFO  : - Path2    File is new               - modtime_write_test
2025/05/09 03:34:00 NOTICE: | +INFO  : Path2:    2 changes:    1 new,    1 modified,    0 deleted
2025/05/09 03:34:00 NOTICE: | @@ -87,0 +89 @@
2025/05/09 03:34:00 NOTICE: | +INFO  : - Path2    Queue copy to Path1       - {path1/}modtime_write_test
2025/05/09 03:34:00 NOTICE: ----------------------------------------------------------
    bisync_test.go:560: TEST rclone_args FAILED - 1 MISCOMPARED FILES
=== RUN   TestBisyncLocalRemote/rmdirs
2025/05/09 03:34:01 DEBUG : Creating backend with remote "/tmp/033208pe/rmdirs/path1"
2025/05/09 03:34:01 INFO  : path2: Making directory
2025/05/09 03:34:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2"
2025/05/09 03:34:01 DEBUG : Starting OpenDrive session with ID: fdf8d7c0882023c8281e9261933d5c67e497cbf5fd8e2355576803464a34fea4
2025/05/09 03:34:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/05/09 03:34:02 DEBUG : Creating backend with remote "/tmp/033208pe/initdir/test_rmdirs-puxakaf5"
2025/05/09 03:34:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : subdir: Making directory with metadata
2025/05/09 03:34:02 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/05/09 03:34:02 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/05/09 03:34:02 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : Local file system at /tmp/033208pe/initdir/test_rmdirs-puxakaf5: Waiting for checks to finish
2025/05/09 03:34:02 DEBUG : Local file system at /tmp/033208pe/initdir/test_rmdirs-puxakaf5: Waiting for transfers to finish
2025/05/09 03:34:02 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/05/09 03:34:02 INFO  : file1.copy1.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/05/09 03:34:02 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:02 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:34:02 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:34:02 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/05/09 03:34:02 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/05/09 03:34:02 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/05/09 03:34:02 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/05/09 03:34:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:02 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:34:02 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:34:02 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:02 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/05/09 03:34:02 DEBUG : Creating backend with remote "/tmp/033208pe/datadir/test_rmdirs-dofavoj1"
2025/05/09 03:34:02 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:02 DEBUG : Local file system at /tmp/033208pe/datadir/test_rmdirs-dofavoj1: Waiting for checks to finish
2025/05/09 03:34:02 DEBUG : Local file system at /tmp/033208pe/datadir/test_rmdirs-dofavoj1: Waiting for transfers to finish
2025/05/09 03:34:02 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:34:02 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/05/09 03:34:02 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:02 ERROR : : error listing: directory not found
2025/05/09 03:34:03 ERROR : : error listing: directory not found
2025/05/09 03:34:03 NOTICE: checking initFs Local file system at /tmp/033208pe/initdir/test_rmdirs-puxakaf5
2025/05/09 03:34:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : subdir: Making directory with metadata
2025/05/09 03:34:03 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:03 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:34:03 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:34:03 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/05/09 03:34:03 INFO  : file1.copy1.txt: Copied (new)
2025/05/09 03:34:03 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/05/09 03:34:03 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:34:03 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/05/09 03:34:03 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/05/09 03:34:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/05/09 03:34:03 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:34:03 DEBUG : Local file system at /tmp/033208pe/rmdirs/path1: Waiting for checks to finish
2025/05/09 03:34:03 DEBUG : Local file system at /tmp/033208pe/rmdirs/path1: Waiting for transfers to finish
2025/05/09 03:34:03 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/05/09 03:34:03 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:34:03 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/05/09 03:34:03 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:34:03 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:03 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/05/09 03:34:03 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:34:03 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:34:03 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:03 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:03 NOTICE: checking Path1 Local file system at /tmp/033208pe/rmdirs/path1
2025/05/09 03:34:03 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:34:03 INFO  : subdir: Making directory
2025/05/09 03:34:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:03 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/rmdirs/path2': Waiting for checks to finish
2025/05/09 03:34:03 DEBUG : OpenDrive root 'rclone-test-venehic8lupa/033208pe/rmdirs/path2': Waiting for transfers to finish
2025/05/09 03:34:05 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:05 INFO  : file1.copy1.txt: Copied (new)
2025/05/09 03:34:06 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:06 INFO  : file1.copy3.txt: Copied (new)
2025/05/09 03:34:06 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:06 INFO  : file1.copy2.txt: Copied (new)
2025/05/09 03:34:07 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:07 INFO  : file1.copy4.txt: Copied (new)
2025/05/09 03:34:07 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:07 INFO  : file1.copy5.txt: Copied (new)
2025/05/09 03:34:07 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:34:08 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:08 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:08 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:34:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:09 INFO  : subdir/file20.txt: Copied (new)
    bisync_test.go:1943: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1943
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:461
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Received unexpected error:
        	            	failed to get folder list: Directory doesn't exist (Error 404)
        	Test:       	TestBisyncLocalRemote/rmdirs
        	Messages:   	setting up path2
2025/05/09 03:34:09 NOTICE: checking path2 OpenDrive root 'rclone-test-venehic8lupa/033208pe/rmdirs/path2'
2025/05/09 03:34:09 NOTICE: (01)  : test rmdirs
2025/05/09 03:34:09 NOTICE: (02)  : test initial bisync
2025/05/09 03:34:09 NOTICE: (03)  : bisync resync
2025/05/09 03:34:11 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:34:11 INFO  : Synching Path1 "/tmp/033208pe/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:11 INFO  : Copying Path2 files to Path1
2025/05/09 03:34:11 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:34:12 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:34:12 INFO  : Resync updating listings
2025/05/09 03:34:12 INFO  : Validating listings for Path1 "/tmp/033208pe/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:12 INFO  : Bisync successful
2025/05/09 03:34:12 NOTICE: (04)  : test 1. delete path1 subdir file
2025/05/09 03:34:12 NOTICE: (05)  : delete-file /tmp/033208pe/rmdirs/path1/subdir/file20.txt
2025/05/09 03:34:12 INFO  : file20.txt: Deleted
2025/05/09 03:34:12 NOTICE: (06)  : test 2. run bisync without remove-empty-dirs
2025/05/09 03:34:12 NOTICE: (07)  : bisync
2025/05/09 03:34:14 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:34:14 INFO  : Synching Path1 "/tmp/033208pe/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:14 INFO  : Building Path1 and Path2 listings
2025/05/09 03:34:14 INFO  : Path1 checking for diffs
2025/05/09 03:34:14 INFO  : - Path1    File was deleted          - subdir/file20.txt
2025/05/09 03:34:14 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/05/09 03:34:14 INFO  : Path2 checking for diffs
2025/05/09 03:34:14 INFO  : Applying changes
2025/05/09 03:34:14 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/subdir/file20.txt
2025/05/09 03:34:14 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:34:15 INFO  : subdir/file20.txt: Deleted
2025/05/09 03:34:15 INFO  : Updating listings
2025/05/09 03:34:15 INFO  : Validating listings for Path1 "/tmp/033208pe/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:15 INFO  : Bisync successful
2025/05/09 03:34:15 NOTICE: (08)  : test 3. confirm the subdir still exists on both paths
2025/05/09 03:34:15 NOTICE: (09)  : list-dirs /tmp/033208pe/rmdirs/path1/
2025/05/09 03:34:15 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:34:15 NOTICE: (10)  : list-dirs TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/
2025/05/09 03:34:16 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/05/09 03:34:16 NOTICE: (11)  : test 4. run bisync with remove-empty-dirs
2025/05/09 03:34:16 NOTICE: (12)  : bisync remove-empty-dirs
2025/05/09 03:34:18 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/09 03:34:18 INFO  : Synching Path1 "/tmp/033208pe/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:18 INFO  : Building Path1 and Path2 listings
2025/05/09 03:34:19 INFO  : Path1 checking for diffs
2025/05/09 03:34:19 INFO  : Path2 checking for diffs
2025/05/09 03:34:19 INFO  : No changes found
2025/05/09 03:34:19 INFO  : Updating listings
2025/05/09 03:34:19 INFO  : Validating listings for Path1 "/tmp/033208pe/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/"
2025/05/09 03:34:19 INFO  : Removing empty directories
2025/05/09 03:34:19 INFO  : subdir: Removing directory
2025/05/09 03:34:19 INFO  : subdir: Removing directory
2025/05/09 03:34:19 INFO  : Bisync successful
2025/05/09 03:34:19 NOTICE: (13)  : test 5. confirm the subdir has been removed on both paths
2025/05/09 03:34:19 NOTICE: (14)  : list-dirs /tmp/033208pe/rmdirs/path1/
2025/05/09 03:34:19 NOTICE: (15)  : list-dirs TestOpenDrive:rclone-test-venehic8lupa/033208pe/rmdirs/path2/
    bisync_test.go:560: TEST rmdirs PASSED
2025/05/09 03:34:20 DEBUG : OpenDrive root 'rclone-test-venehic8lupa': Purge remote
--- FAIL: TestBisyncLocalRemote (134.02s)
    --- PASS: TestBisyncLocalRemote/compare_all (31.38s)
    --- FAIL: TestBisyncLocalRemote/max_delete_path1 (27.93s)
    --- FAIL: TestBisyncLocalRemote/rclone_args (53.77s)
    --- FAIL: TestBisyncLocalRemote/rmdirs (19.26s)
=== RUN   TestBisyncRemoteRemote
2025/05/09 03:34:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku"
2025/05/09 03:34:21 DEBUG : Starting OpenDrive session with ID: 2fcd539b340c6dfd632b56f26481d071552ec5ead2af2739513dbc4f079717e6
2025/05/09 03:34:22 NOTICE: remote: TestOpenDrive:rclone-test-zuyihik8weku
=== RUN   TestBisyncRemoteRemote/check_filename
2025/05/09 03:34:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku/033422se"
2025/05/09 03:34:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/09 03:34:22 DEBUG : Starting OpenDrive session with ID: f91c5e4797e429b72921b37f532771266d1de9b42f29487f7c5968bf600e9cec
2025/05/09 03:34:23 INFO  : path1: Making directory
2025/05/09 03:34:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1"
2025/05/09 03:34:24 DEBUG : Starting OpenDrive session with ID: 7acff5f6a132086b86e9449d8b00130bebb300827b864fa5aea0d7da2ea26d0b
2025/05/09 03:34:25 INFO  : path2: Making directory
2025/05/09 03:34:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2"
2025/05/09 03:34:26 DEBUG : Starting OpenDrive session with ID: beddc7db2211fcd1719e9c170d330acef4cb67a82492523966bb3e27db47a4fc
2025/05/09 03:34:27 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/initial"
2025/05/09 03:34:27 DEBUG : Creating backend with remote "/tmp/033422se/initdir/test_check_filename-jahiyed2"
2025/05/09 03:34:27 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : subdir: Making directory with metadata
2025/05/09 03:34:27 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/05/09 03:34:27 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/05/09 03:34:27 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : Local file system at /tmp/033422se/initdir/test_check_filename-jahiyed2: Waiting for checks to finish
2025/05/09 03:34:27 DEBUG : Local file system at /tmp/033422se/initdir/test_check_filename-jahiyed2: Waiting for transfers to finish
2025/05/09 03:34:27 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:27 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:34:27 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:27 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:27 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:34:27 INFO  : file2.txt: Copied (new)
2025/05/09 03:34:27 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:27 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:34:27 INFO  : file3.txt: Copied (new)
2025/05/09 03:34:27 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:27 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:34:27 INFO  : file4.txt: Copied (new)
2025/05/09 03:34:27 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:27 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/05/09 03:34:27 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:34:27 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:27 DEBUG : subdir/.chk_file.cf9a93ac.partial: renamed to: subdir/.chk_file
2025/05/09 03:34:27 INFO  : subdir/.chk_file: Copied (new)
2025/05/09 03:34:27 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:27 DEBUG : .chk_file.cf9a93ac.partial: renamed to: .chk_file
2025/05/09 03:34:27 INFO  : .chk_file: Copied (new)
2025/05/09 03:34:27 INFO  : subdir: Set directory modification time (using SetModTime)
2025/05/09 03:34:27 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/modfiles"
2025/05/09 03:34:27 DEBUG : Creating backend with remote "/tmp/033422se/datadir/test_check_filename-vobubig9"
2025/05/09 03:34:27 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:27 DEBUG : Local file system at /tmp/033422se/datadir/test_check_filename-vobubig9: Waiting for checks to finish
2025/05/09 03:34:27 DEBUG : Local file system at /tmp/033422se/datadir/test_check_filename-vobubig9: Waiting for transfers to finish
2025/05/09 03:34:27 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/05/09 03:34:27 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/05/09 03:34:27 INFO  : hold.txt: Copied (new)
2025/05/09 03:34:27 ERROR : : error listing: directory not found
2025/05/09 03:34:28 ERROR : : error listing: directory not found
2025/05/09 03:34:28 NOTICE: checking initFs Local file system at /tmp/033422se/initdir/test_check_filename-jahiyed2
2025/05/09 03:34:28 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:28 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:28 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:28 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:28 INFO  : subdir: Making directory
2025/05/09 03:34:29 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:29 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:29 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path1': Waiting for checks to finish
2025/05/09 03:34:29 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path1': Waiting for transfers to finish
2025/05/09 03:34:30 DEBUG : .chk_file: Uploading chunk 0, size=109, remain=0
2025/05/09 03:34:31 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:31 INFO  : .chk_file: Copied (new)
2025/05/09 03:34:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:31 INFO  : file2.txt: Copied (new)
2025/05/09 03:34:32 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:32 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:32 DEBUG : subdir/.chk_file: Uploading chunk 0, size=109, remain=0
2025/05/09 03:34:32 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:32 INFO  : file3.txt: Copied (new)
2025/05/09 03:34:33 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:33 INFO  : subdir/.chk_file: Copied (new)
2025/05/09 03:34:33 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:33 INFO  : file4.txt: Copied (new)
2025/05/09 03:34:33 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:33 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:34:33 NOTICE: checking Path1 OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path1'
2025/05/09 03:34:34 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:34 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:34 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:34 INFO  : subdir: Making directory
2025/05/09 03:34:35 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/05/09 03:34:35 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/05/09 03:34:35 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path2': Waiting for checks to finish
2025/05/09 03:34:35 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path2': Waiting for transfers to finish
2025/05/09 03:34:35 DEBUG : .chk_file: Uploading chunk 0, size=109, remain=0
2025/05/09 03:34:36 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:36 INFO  : .chk_file: Copied (new)
2025/05/09 03:34:37 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:37 INFO  : file2.txt: Copied (new)
2025/05/09 03:34:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:37 INFO  : file1.txt: Copied (new)
2025/05/09 03:34:37 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:37 INFO  : file3.txt: Copied (new)
2025/05/09 03:34:38 DEBUG : subdir/.chk_file: Uploading chunk 0, size=109, remain=0
2025/05/09 03:34:38 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:38 INFO  : file4.txt: Copied (new)
2025/05/09 03:34:39 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:34:39 INFO  : subdir/file20.txt: Copied (new)
2025/05/09 03:34:39 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:34:39 INFO  : subdir/.chk_file: Copied (new)
2025/05/09 03:34:39 NOTICE: checking path2 OpenDrive root 'rclone-test-zuyihik8weku/033422se/check_filename/path2'
2025/05/09 03:34:39 NOTICE: (01)  : test check-filename
2025/05/09 03:34:39 NOTICE: (02)  : test initial bisync
2025/05/09 03:34:39 NOTICE: (03)  : bisync resync
2025/05/09 03:34:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34:43 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/09 03:34:43 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:34:43 INFO  : Copying Path2 files to Path1
2025/05/09 03:34:43 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:34:43 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:34:44 INFO  : Resync updating listings
2025/05/09 03:34:44 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:34:44 INFO  : Bisync successful
2025/05/09 03:34:44 NOTICE: (04)  : test 1. see that check-access passes with the initial setup
2025/05/09 03:34:44 NOTICE: (05)  : bisync check-access check-filename=.chk_file
2025/05/09 03:34:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34:48 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/09 03:34:48 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:34:48 INFO  : Building Path1 and Path2 listings
2025/05/09 03:34:48 INFO  : Path1 checking for diffs
2025/05/09 03:34:48 INFO  : Path2 checking for diffs
2025/05/09 03:34:48 INFO  : Checking access health
2025/05/09 03:34:48 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/09 03:34:48 INFO  : No changes found
2025/05/09 03:34:48 INFO  : Updating listings
2025/05/09 03:34:48 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:34:48 INFO  : Bisync successful
2025/05/09 03:34:48 NOTICE: (06)  : copy-listings initial-pass
2025/05/09 03:34:48 NOTICE: (07)  : test 2. delete the remote subdir .chk_file, run sync. should fail critical.
2025/05/09 03:34:48 NOTICE: (08)  : delete-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/subdir/.chk_file
2025/05/09 03:34:50 INFO  : .chk_file: Deleted
2025/05/09 03:34:50 NOTICE: (09)  : bisync check-access check-filename=.chk_file
2025/05/09 03:34:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:34: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/05/09 03:34:54 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:34:54 INFO  : Building Path1 and Path2 listings
2025/05/09 03:34:54 INFO  : Path1 checking for diffs
2025/05/09 03:34:54 INFO  : Path2 checking for diffs
2025/05/09 03:34:54 INFO  : - Path2    File was deleted          - subdir/.chk_file
2025/05/09 03:34:54 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/05/09 03:34:54 INFO  : Checking access health
2025/05/09 03:34:54 ERROR : Access test failed: Path1 count 2, Path2 count 1 - .chk_file
2025/05/09 03:34:54 ERROR : -          Access test failed: Path1 file not found in Path2 - subdir/.chk_file
2025/05/09 03:34:54 ERROR : Bisync critical error: check file check failed
2025/05/09 03:34:54 ERROR : Bisync aborted. Must run --resync to recover.
2025/05/09 03:34:54 NOTICE: Bisync error: bisync aborted
2025/05/09 03:34:54 NOTICE: (10)  : move-listings path2-missing
2025/05/09 03:34:54 NOTICE: (11)  : test 3. put the remote subdir .chk_file back, run resync.
2025/05/09 03:34:54 NOTICE: (12)  : copy-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/subdir/.chk_file TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/subdir/
2025/05/09 03:34:59 INFO  : .chk_file: Copied (server-side copy)
2025/05/09 03:34:59 NOTICE: (13)  : bisync check-access resync check-filename=.chk_file
2025/05/09 03:35:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:35:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/09 03:35:03 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:35:03 INFO  : Copying Path2 files to Path1
2025/05/09 03:35:03 INFO  : Checking access health
2025/05/09 03:35:03 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/09 03:35:03 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:35:04 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:35:04 INFO  : Resync updating listings
2025/05/09 03:35:04 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:35:04 INFO  : Bisync successful
2025/05/09 03:35:04 NOTICE: (14)  : test 4. run sync with check-access. should pass.
2025/05/09 03:35:04 NOTICE: (15)  : bisync check-access check-filename=.chk_file
2025/05/09 03:35:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:35: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/05/09 03:35:08 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:35:08 INFO  : Building Path1 and Path2 listings
2025/05/09 03:35:09 INFO  : Path1 checking for diffs
2025/05/09 03:35:09 INFO  : Path2 checking for diffs
2025/05/09 03:35:09 INFO  : Checking access health
2025/05/09 03:35:09 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/09 03:35:09 INFO  : No changes found
2025/05/09 03:35:09 INFO  : Updating listings
2025/05/09 03:35:09 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/check_filename/path2/"
2025/05/09 03:35:09 INFO  : Bisync successful
    bisync_test.go:560: TEST check_filename PASSED
=== RUN   TestBisyncRemoteRemote/dry_run
2025/05/09 03:35:10 INFO  : path1: Making directory
2025/05/09 03:35:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1"
2025/05/09 03:35:10 DEBUG : Starting OpenDrive session with ID: d85dd17184dd7d74421dfea292290874857148921051318196394bac4df67078
2025/05/09 03:35:11 INFO  : path2: Making directory
2025/05/09 03:35:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2"
2025/05/09 03:35:12 DEBUG : Starting OpenDrive session with ID: 8a8ed716f71f83cc8dd977ab21223ae5fcea9afcb31ff353448c0935310084f8
2025/05/09 03:35:13 DEBUG : Creating backend with remote "/tmp/033422se/initdir/test_dry_run-basomex8"
2025/05/09 03:35:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : Local file system at /tmp/033422se/initdir/test_dry_run-basomex8: Waiting for checks to finish
2025/05/09 03:35:13 DEBUG : Local file system at /tmp/033422se/initdir/test_dry_run-basomex8: Waiting for transfers to finish
2025/05/09 03:35:13 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/05/09 03:35:13 INFO  : file3.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/05/09 03:35:13 INFO  : file1.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/05/09 03:35:13 INFO  : file2.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/05/09 03:35:13 INFO  : file4.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/05/09 03:35:13 INFO  : file5.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/05/09 03:35:13 INFO  : file7.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:35:13 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/05/09 03:35:13 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:35:13 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:13 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/05/09 03:35:13 INFO  : file6.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : Creating backend with remote "/tmp/033422se/datadir/test_dry_run-nakezip8"
2025/05/09 03:35:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : Local file system at /tmp/033422se/datadir/test_dry_run-nakezip8: Waiting for checks to finish
2025/05/09 03:35:13 DEBUG : Local file system at /tmp/033422se/datadir/test_dry_run-nakezip8: Waiting for transfers to finish
2025/05/09 03:35:13 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/05/09 03:35:13 DEBUG : file2.txt.f8ac1735.partial: renamed to: file2.txt
2025/05/09 03:35:13 INFO  : file2.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:35:13 DEBUG : file10.txt.925c801f.partial: renamed to: file10.txt
2025/05/09 03:35:13 INFO  : file10.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:35:13 DEBUG : file11.txt.925c801f.partial: renamed to: file11.txt
2025/05/09 03:35:13 INFO  : file11.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/05/09 03:35:13 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/05/09 03:35:13 INFO  : file5L.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/05/09 03:35:13 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/05/09 03:35:13 INFO  : file5R.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:35:13 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/05/09 03:35:13 INFO  : file1.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:35:13 DEBUG : file7.txt.925c801f.partial: renamed to: file7.txt
2025/05/09 03:35:13 INFO  : file7.txt: Copied (new)
2025/05/09 03:35:13 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/05/09 03:35:13 DEBUG : file6.txt.925c801f.partial: renamed to: file6.txt
2025/05/09 03:35:13 INFO  : file6.txt: Copied (new)
2025/05/09 03:35:13 ERROR : : error listing: directory not found
2025/05/09 03:35:13 ERROR : : error listing: directory not found
2025/05/09 03:35:13 NOTICE: checking initFs Local file system at /tmp/033422se/initdir/test_dry_run-basomex8
2025/05/09 03:35:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:35:13 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path1': Waiting for checks to finish
2025/05/09 03:35:13 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path1': Waiting for transfers to finish
2025/05/09 03:35:16 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:16 INFO  : file2.txt: Copied (new)
2025/05/09 03:35:16 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:16 INFO  : file3.txt: Copied (new)
2025/05/09 03:35:16 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:16 INFO  : file4.txt: Copied (new)
2025/05/09 03:35:18 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:18 INFO  : file5.txt: Copied (new)
2025/05/09 03:35:18 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:18 INFO  : file1.txt: Copied (new)
2025/05/09 03:35:18 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:18 INFO  : file7.txt: Copied (new)
2025/05/09 03:35:18 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:18 INFO  : file6.txt: Copied (new)
2025/05/09 03:35:19 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:35:19 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:35:19 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:35:19 NOTICE: checking Path1 OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path1'
2025/05/09 03:35:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/05/09 03:35:20 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': Waiting for checks to finish
2025/05/09 03:35:20 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': Waiting for transfers to finish
2025/05/09 03:35:22 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:22 INFO  : file1.txt: Copied (new)
2025/05/09 03:35:22 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:22 INFO  : file2.txt: Copied (new)
2025/05/09 03:35:22 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:22 INFO  : file3.txt: Copied (new)
2025/05/09 03:35:24 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:24 INFO  : file5.txt: Copied (new)
2025/05/09 03:35:24 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:24 INFO  : file6.txt: Copied (new)
2025/05/09 03:35:24 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:24 INFO  : file7.txt: Copied (new)
2025/05/09 03:35:24 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/05/09 03:35:25 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/09 03:35:25 INFO  : file4.txt: Copied (new)
2025/05/09 03:35:25 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/05/09 03:35:25 INFO  : RCLONE_TEST: Copied (new)
2025/05/09 03:35:25 NOTICE: checking path2 OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2'
2025/05/09 03:35:25 NOTICE: (01)  : test dry-run
2025/05/09 03:35:25 NOTICE: (02)  : test initial bisync
2025/05/09 03:35:25 NOTICE: (03)  : bisync resync
2025/05/09 03:35:30 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:35: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/05/09 03:35:30 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:35:30 INFO  : Copying Path2 files to Path1
2025/05/09 03:35:30 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/09 03:35:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/09 03:35:30 INFO  : Resync updating listings
2025/05/09 03:35:30 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:35:30 INFO  : Bisync successful
2025/05/09 03:35:30 NOTICE: (04)  : test new on path2 - file10
2025/05/09 03:35:30 NOTICE: (05)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file10.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/
2025/05/09 03:35:33 INFO  : file10.txt: Copied (new)
2025/05/09 03:35:33 NOTICE: (06)  : test newer on path2 - file1
2025/05/09 03:35:33 NOTICE: (07)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file1.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/
2025/05/09 03:35:35 INFO  : file1.txt: Copied (replaced existing)
2025/05/09 03:35:35 NOTICE: (08)  : test new on path1 - file11
2025/05/09 03:35:35 NOTICE: (09)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file11.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/
2025/05/09 03:35:38 INFO  : file11.txt: Copied (new)
2025/05/09 03:35:38 NOTICE: (10)  : test newer on path1 - file2
2025/05/09 03:35:38 NOTICE: (11)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file2.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/
2025/05/09 03:35:41 INFO  : file2.txt: Copied (replaced existing)
2025/05/09 03:35:41 NOTICE: (12)  : test deleted on path2 - file3
2025/05/09 03:35:41 NOTICE: (13)  : delete-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file3.txt
2025/05/09 03:35:42 INFO  : file3.txt: Deleted
2025/05/09 03:35:42 NOTICE: (14)  : test deleted on path1 - file4
2025/05/09 03:35:42 NOTICE: (15)  : delete-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file4.txt
2025/05/09 03:35:44 INFO  : file4.txt: Deleted
2025/05/09 03:35:44 NOTICE: (16)  : test changed on path2 and on path1 - file5 (file5R, file5L)
2025/05/09 03:35:44 NOTICE: (17)  : touch-glob 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/ file5R.txt
2025/05/09 03:35:44 NOTICE: (18)  : copy-as /tmp/033422se/datadir/test_dry_run-nakezip8/file5R.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/ file5.txt
2025/05/09 03:35:47 INFO  : file5R.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:35:47 NOTICE: (19)  : touch-glob 2001-03-04 /tmp/033422se/datadir/test_dry_run-nakezip8/ file5L.txt
2025/05/09 03:35:47 NOTICE: (20)  : copy-as /tmp/033422se/datadir/test_dry_run-nakezip8/file5L.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/ file5.txt
2025/05/09 03:35:49 INFO  : file5L.txt: Copied (replaced existing) to: file5.txt
2025/05/09 03:35:49 NOTICE: (21)  : test newer on path2 and deleted on path1 - file6
2025/05/09 03:35:49 NOTICE: (22)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file6.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/
2025/05/09 03:35:52 INFO  : file6.txt: Copied (replaced existing)
2025/05/09 03:35:52 NOTICE: (23)  : delete-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file6.txt
2025/05/09 03:35:52 INFO  : file6.txt: Deleted
2025/05/09 03:35:52 NOTICE: (24)  : test newer on path1 and deleted on path2 - file7
2025/05/09 03:35:52 NOTICE: (25)  : touch-copy 2001-01-02 /tmp/033422se/datadir/test_dry_run-nakezip8/file7.txt TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/
2025/05/09 03:35:54 INFO  : file7.txt: Copied (replaced existing)
2025/05/09 03:35:54 NOTICE: (26)  : delete-file TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file7.txt
2025/05/09 03:35:55 INFO  : file7.txt: Deleted
2025/05/09 03:35:55 NOTICE: (27)  : test sync with dry-run and resync
2025/05/09 03:35:55 NOTICE: (28)  : bisync dry-run resync
2025/05/09 03:35:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:35: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/05/09 03:35:59 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:35:59 INFO  : Copying Path2 files to Path1
2025/05/09 03:35:59 NOTICE: - Path2    Resync is copying files to         - Path1
2025/05/09 03:35:59 NOTICE: file10.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:35:59 NOTICE: file4.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:35:59 NOTICE: file6.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:35:59 NOTICE: - Path1    Resync is copying files to         - Path2
2025/05/09 03:35:59 NOTICE: file11.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:35:59 NOTICE: file3.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:35:59 NOTICE: file1.txt: Skipped copy as --dry-run is set (size 0)
2025/05/09 03:35:59 NOTICE: file2.txt: Skipped copy as --dry-run is set (size 13)
2025/05/09 03:35:59 NOTICE: file7.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:35:59 NOTICE: file5.txt: Skipped copy as --dry-run is set (size 39)
2025/05/09 03:35:59 INFO  : Resync updating listings
2025/05/09 03:36:00 INFO  : Bisync successful
2025/05/09 03:36:00 NOTICE: (29)  : copy-listings dryrun-resync
2025/05/09 03:36:00 NOTICE: (30)  : test sync with dry-run
2025/05/09 03:36:00 NOTICE: (31)  : bisync dry-run
2025/05/09 03:36:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:36: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/05/09 03:36:04 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:36:04 INFO  : Building Path1 and Path2 listings
2025/05/09 03:36:04 INFO  : Path1 checking for diffs
2025/05/09 03:36:04 NOTICE: - Path1    File changed: size (larger), time (newer) - file2.txt
2025/05/09 03:36:04 NOTICE: - Path1    File was deleted          - file4.txt
2025/05/09 03:36:04 NOTICE: - Path1    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:36:04 NOTICE: - Path1    File was deleted          - file6.txt
2025/05/09 03:36:04 NOTICE: - Path1    File changed: size (larger), time (newer) - file7.txt
2025/05/09 03:36:04 NOTICE: - Path1    File is new               - file11.txt
2025/05/09 03:36:04 INFO  : Path1:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:36:04 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:36:04 INFO  : Path2 checking for diffs
2025/05/09 03:36:04 NOTICE: - Path2    File changed: size (larger), time (newer) - file1.txt
2025/05/09 03:36:04 NOTICE: - Path2    File was deleted          - file3.txt
2025/05/09 03:36:04 NOTICE: - Path2    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:36:04 NOTICE: - Path2    File changed: size (larger), time (newer) - file6.txt
2025/05/09 03:36:04 NOTICE: - Path2    File was deleted          - file7.txt
2025/05/09 03:36:04 NOTICE: - Path2    File is new               - file10.txt
2025/05/09 03:36:04 INFO  : Path2:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:36:04 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:36:04 INFO  : Applying changes
2025/05/09 03:36:04 INFO  : Checking potential conflicts...
2025/05/09 03:36:05 ERROR : file5.txt: md5 differ
2025/05/09 03:36:05 NOTICE: OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': 1 differences found
2025/05/09 03:36:05 NOTICE: OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': 1 errors while checking
2025/05/09 03:36:05 INFO  : Finished checking the potential conflicts. 1 differences found
2025/05/09 03:36:05 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file11.txt
2025/05/09 03:36:05 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file2.txt
2025/05/09 03:36:05 NOTICE: - Path2    Queue delete              - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file4.txt
2025/05/09 03:36:05 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/05/09 03:36:05 NOTICE: file5.txt: Skipped rename as --dry-run is set
2025/05/09 03:36:05 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file5.txt.conflict1
2025/05/09 03:36:05 NOTICE: file5.txt: Skipped rename as --dry-run is set
2025/05/09 03:36:05 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file5.txt.conflict2
2025/05/09 03:36:05 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file6.txt
2025/05/09 03:36:05 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file7.txt
2025/05/09 03:36:05 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file1.txt
2025/05/09 03:36:05 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file10.txt
2025/05/09 03:36:05 NOTICE: - Path1    Queue delete              - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file3.txt
2025/05/09 03:36:05 NOTICE: - Path2    Do queued copies to                - Path1
2025/05/09 03:36:05 NOTICE: file10.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:36:05 NOTICE: file6.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:36:05 NOTICE: file1.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:36:05 NOTICE: file3.txt: Skipped delete as --dry-run is set (size 0)
2025/05/09 03:36:05 NOTICE: - Path1    Do queued copies to                - Path2
2025/05/09 03:36:05 NOTICE: file11.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:36:05 NOTICE: file7.txt: Skipped copy as --dry-run is set (size 19)
2025/05/09 03:36:05 NOTICE: file2.txt: Skipped copy as --dry-run is set (size 13)
2025/05/09 03:36:05 NOTICE: file4.txt: Skipped delete as --dry-run is set (size 0)
2025/05/09 03:36:05 INFO  : Updating listings
2025/05/09 03:36:06 INFO  : Bisync successful
2025/05/09 03:36:06 NOTICE: (32)  : copy-listings dryrun
2025/05/09 03:36:06 NOTICE: (33)  : test sync without dry-run
2025/05/09 03:36:06 NOTICE: (34)  : bisync
2025/05/09 03:36:10 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/09 03:36:10 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/09 03:36:10 INFO  : Synching Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" with Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:36:10 INFO  : Building Path1 and Path2 listings
2025/05/09 03:36:10 INFO  : Path1 checking for diffs
2025/05/09 03:36:10 INFO  : - Path1    File changed: size (larger), time (newer) - file2.txt
2025/05/09 03:36:10 INFO  : - Path1    File was deleted          - file4.txt
2025/05/09 03:36:10 INFO  : - Path1    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:36:10 INFO  : - Path1    File was deleted          - file6.txt
2025/05/09 03:36:10 INFO  : - Path1    File changed: size (larger), time (newer) - file7.txt
2025/05/09 03:36:10 INFO  : - Path1    File is new               - file11.txt
2025/05/09 03:36:10 INFO  : Path1:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:36:10 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:36:10 INFO  : Path2 checking for diffs
2025/05/09 03:36:10 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/05/09 03:36:10 INFO  : - Path2    File was deleted          - file3.txt
2025/05/09 03:36:10 INFO  : - Path2    File changed: size (larger), time (newer) - file5.txt
2025/05/09 03:36:10 INFO  : - Path2    File changed: size (larger), time (newer) - file6.txt
2025/05/09 03:36:10 INFO  : - Path2    File was deleted          - file7.txt
2025/05/09 03:36:10 INFO  : - Path2    File is new               - file10.txt
2025/05/09 03:36:10 INFO  : Path2:    6 changes:    1 new,    3 modified,    2 deleted
2025/05/09 03:36:10 INFO  : (Modified:    3 newer,    0 older,    3 larger,    0 smaller)
2025/05/09 03:36:10 INFO  : Applying changes
2025/05/09 03:36:10 INFO  : Checking potential conflicts...
2025/05/09 03:36:10 ERROR : file5.txt: md5 differ
2025/05/09 03:36:10 NOTICE: OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': 1 differences found
2025/05/09 03:36:10 NOTICE: OpenDrive root 'rclone-test-zuyihik8weku/033422se/dry_run/path2': 1 errors while checking
2025/05/09 03:36:10 INFO  : Finished checking the potential conflicts. 1 differences found
2025/05/09 03:36:10 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file11.txt
2025/05/09 03:36:10 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file2.txt
2025/05/09 03:36:10 INFO  : - Path2    Queue delete              - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file4.txt
2025/05/09 03:36:10 NOTICE: - WARNING  New or changed in both paths       - file5.txt
2025/05/09 03:36:10 NOTICE: - Path1    Renaming Path1 copy                - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file5.txt.conflict1
2025/05/09 03:36:11 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict1
2025/05/09 03:36:11 NOTICE: - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file5.txt.conflict1
2025/05/09 03:36:11 NOTICE: - Path2    Renaming Path2 copy                - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file5.txt.conflict2
2025/05/09 03:36:11 INFO  : file5.txt: Moved (server-side) to: file5.txt.conflict2
2025/05/09 03:36:11 NOTICE: - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file5.txt.conflict2
2025/05/09 03:36:11 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file6.txt
2025/05/09 03:36:11 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/file7.txt
2025/05/09 03:36:11 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file1.txt
2025/05/09 03:36:11 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file10.txt
2025/05/09 03:36:11 INFO  : - Path1    Queue delete              - TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/file3.txt
2025/05/09 03:36:11 INFO  : - Path2    Do queued copies to                - Path1
2025/05/09 03:36:12 INFO  : file10.txt: Copied (server-side copy)
2025/05/09 03:36:12 INFO  : file6.txt: Copied (server-side copy)
2025/05/09 03:36:13 INFO  : file1.txt: Copied (server-side copy)
2025/05/09 03:36:13 INFO  : file5.txt.conflict2: Copied (server-side copy)
2025/05/09 03:36:13 INFO  : file3.txt: Deleted
2025/05/09 03:36:13 INFO  : - Path1    Do queued copies to                - Path2
2025/05/09 03:36:14 INFO  : file11.txt: Copied (server-side copy)
2025/05/09 03:36:14 INFO  : file7.txt: Copied (server-side copy)
2025/05/09 03:36:14 INFO  : file2.txt: Copied (server-side copy)
2025/05/09 03:36:15 INFO  : file5.txt.conflict1: Copied (server-side copy)
2025/05/09 03:36:15 INFO  : file4.txt: Deleted
2025/05/09 03:36:15 INFO  : Updating listings
2025/05/09 03:36:15 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path1/" vs Path2 "TestOpenDrive:rclone-test-zuyihik8weku/033422se/dry_run/path2/"
2025/05/09 03:36:15 INFO  : Bisync successful
    bisync_test.go:560: TEST dry_run PASSED
2025/05/09 03:36:16 DEBUG : OpenDrive root 'rclone-test-zuyihik8weku': Purge remote
--- PASS: TestBisyncRemoteRemote (115.77s)
    --- PASS: TestBisyncRemoteRemote/check_filename (47.88s)
    --- PASS: TestBisyncRemoteRemote/dry_run (66.24s)
FAIL
2025/05/09 03:36:17 DEBUG : OpenDrive root 'rclone-test-duyupoc4cewe': Purge remote
2025/05/09 03:36:17 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncLocalRemote$/^(compare_all|max_delete_path1|rclone_args|rmdirs)$|^TestBisyncRemoteLocal$/^(createemptysrcdirs|dry_run)$|^TestBisyncRemoteRemote$/^(check_filename|dry_run)$'" - Finished ERROR in 6m35.783526946s (try 3/5): exit status 1: Failed [TestBisyncRemoteLocal/createemptysrcdirs TestBisyncRemoteLocal/dry_run TestBisyncLocalRemote/max_delete_path1 TestBisyncLocalRemote/rclone_args TestBisyncLocalRemote/rmdirs]