"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncLocalRemote$/^rclone_args$|^TestBisyncRemoteLocal$/^filtersfile_checks$|^TestBisyncRemoteRemote$/^(max_delete_path2_force|rmdirs|volatile)$'" - Starting (try 2/5)
2025/03/06 08:57:39 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-pofutus3rubu"
2025/03/06 08:57:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/06 08:57:39 DEBUG : Starting OpenDrive session with ID: f3ff9a59773a4fc1b3a33f54c54b8dd3373225e5fe2b19da15457df3e16c134d
2025/03/06 08:57:40 DEBUG : Creating backend with remote "/tmp/rclone720205610"
=== RUN TestBisyncRemoteLocal
2025/03/06 08:57:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-puvulez9gomu"
2025/03/06 08:57:40 DEBUG : Starting OpenDrive session with ID: e5a954a96aabe625d282b376f99d955c83b851ce4e30886a3d53e461174c4043
2025/03/06 08:57:41 NOTICE: remote: TestOpenDrive:rclone-test-puvulez9gomu
=== RUN TestBisyncRemoteLocal/filtersfile_checks
2025/03/06 08:57:41 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-puvulez9gomu/085741di"
2025/03/06 08:57:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/06 08:57:41 DEBUG : Starting OpenDrive session with ID: 91d71f3726a8ab3248cf3a3fae4427e78d8a079c4520156f7e86fba9c5db8496
2025/03/06 08:57:41 INFO : path1: Making directory
2025/03/06 08:57:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1"
2025/03/06 08:57:43 DEBUG : Starting OpenDrive session with ID: 04271d1dad5e269fb69e596f1a1d83cf0e59906fc192ba957ec55091c7513944
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/tmp/085741di"
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/tmp/085741di/filtersfile_checks/path2"
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/initial"
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/tmp/085741di/initdir/test_filtersfile_checks-buyiguy6"
2025/03/06 08:57:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : subdir: Making directory with metadata
2025/03/06 08:57:44 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/06 08:57:44 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/06 08:57:44 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : Local file system at /tmp/085741di/initdir/test_filtersfile_checks-buyiguy6: Waiting for checks to finish
2025/03/06 08:57:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:44 DEBUG : Local file system at /tmp/085741di/initdir/test_filtersfile_checks-buyiguy6: Waiting for transfers to finish
2025/03/06 08:57:44 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:44 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/06 08:57:44 INFO : file1.txt: Copied (new)
2025/03/06 08:57:44 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 08:57:44 INFO : file2.txt: Copied (new)
2025/03/06 08:57:44 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:44 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/06 08:57:44 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:57:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:57:44 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 08:57:44 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:57:44 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/modfiles"
2025/03/06 08:57:44 DEBUG : Creating backend with remote "/tmp/085741di/datadir/test_filtersfile_checks-zagofar9"
2025/03/06 08:57:44 DEBUG : filtersfile.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : filtersfile2.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:44 DEBUG : Local file system at /tmp/085741di/datadir/test_filtersfile_checks-zagofar9: Waiting for checks to finish
2025/03/06 08:57:44 DEBUG : Local file system at /tmp/085741di/datadir/test_filtersfile_checks-zagofar9: Waiting for transfers to finish
2025/03/06 08:57:44 DEBUG : filtersfile.txt: md5 = cbf4389cb34d9e160689d1578eb5707f OK
2025/03/06 08:57:44 DEBUG : filtersfile.txt.f1af69b0.partial: renamed to: filtersfile.txt
2025/03/06 08:57:44 INFO : filtersfile.txt: Copied (new)
2025/03/06 08:57:44 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/03/06 08:57:44 DEBUG : filtersfile2.txt: md5 = 95acc1e3b5beda465d9b2cb32808588c OK
2025/03/06 08:57:44 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/03/06 08:57:44 INFO : hold.txt: Copied (new)
2025/03/06 08:57:44 DEBUG : filtersfile2.txt.9f8671d3.partial: renamed to: filtersfile2.txt
2025/03/06 08:57:44 INFO : filtersfile2.txt: Copied (new)
2025/03/06 08:57:44 ERROR : : error listing: directory not found
2025/03/06 08:57:44 ERROR : : error listing: directory not found
2025/03/06 08:57:44 NOTICE: checking initFs Local file system at /tmp/085741di/initdir/test_filtersfile_checks-buyiguy6
2025/03/06 08:57:45 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:45 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:45 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:57:45 INFO : subdir: Making directory
2025/03/06 08:57:46 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:46 DEBUG : OpenDrive root 'rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1': Waiting for checks to finish
2025/03/06 08:57:46 DEBUG : OpenDrive root 'rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1': Waiting for transfers to finish
2025/03/06 08:57:47 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 08:57:48 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:48 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:57:48 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:48 INFO : file2.txt: Copied (new)
2025/03/06 08:57:48 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:48 INFO : file1.txt: Copied (new)
2025/03/06 08:57:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:57:49 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:57:49 NOTICE: checking Path1 OpenDrive root 'rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1'
2025/03/06 08:57:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:57:49 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:49 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:49 DEBUG : subdir: Making directory with metadata
2025/03/06 08:57:49 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/06 08:57:49 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/06 08:57:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:57:49 DEBUG : Local file system at /tmp/085741di/filtersfile_checks/path2: Waiting for checks to finish
2025/03/06 08:57:49 DEBUG : Local file system at /tmp/085741di/filtersfile_checks/path2: Waiting for transfers to finish
2025/03/06 08:57:49 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:49 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 08:57:49 INFO : file2.txt: Copied (new)
2025/03/06 08:57:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:49 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:57:49 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/06 08:57:49 INFO : file1.txt: Copied (new)
2025/03/06 08:57:49 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/06 08:57:49 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:57:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:57:49 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 08:57:49 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:57:49 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:49 NOTICE: checking path2 Local file system at /tmp/085741di/filtersfile_checks/path2
2025/03/06 08:57:49 NOTICE: [36m(01) :[0m [34mtest filtersfile-checks[0m
2025/03/06 08:57:49 NOTICE: [36m(02) :[0m [34mtest initial bisync[0m
2025/03/06 08:57:49 NOTICE: [36m(03) :[0m [34mbisync resync[0m
2025/03/06 08:57:51 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:51 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:57: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/03/06 08:57:51 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:51 INFO : Copying Path2 files to Path1
2025/03/06 08:57:51 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:57:52 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:57:52 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:52 INFO : Resync updating listings
2025/03/06 08:57:52 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" vs Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:52 INFO : [32mBisync successful[0m
2025/03/06 08:57:52 NOTICE: [36m(04) :[0m [34mtest 1. inject filters file in workdir.[0m
2025/03/06 08:57:52 NOTICE: [36m(05) :[0m [34mcopy-file /tmp/085741di/datadir/test_filtersfile_checks-zagofar9/filtersfile.txt /tmp/085741di/workdir/[0m
2025/03/06 08:57:52 INFO : filtersfile.txt: Copied (new)
2025/03/06 08:57:52 NOTICE: [36m(06) :[0m [34mtest 2. run with filters-file but without md5. should abort.[0m
2025/03/06 08:57:52 NOTICE: [36m(07) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:57:54 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:54 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:57:54 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:57:54 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:54 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:57:54 ERROR : [31mBisync critical error: filters file md5 hash not found (must run --resync): /tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:57:54 ERROR : [31mBisync aborted. Must run --resync to recover.[0m
2025/03/06 08:57:54 NOTICE: Bisync error: bisync aborted
2025/03/06 08:57:54 NOTICE: [36m(08) :[0m [34mtest 3. run without filters-file. should be blocked due to prior abort.[0m
2025/03/06 08:57:54 NOTICE: [36m(09) :[0m [34mbisync[0m
2025/03/06 08:57:56 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:56 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:57:56 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:57:56 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:56 ERROR : [31mBisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run
[35mTip: here are the filenames we were looking for. Do they exist?
[0m[36mPath1: [94m/tmp/085741di/workdir/TestOpenDrive_rclone-test-puvulez9gomu_085741di_filtersfile_checks_path1..tmp_085741di_filtersfile_checks_path2.path1.lst[0m
[0m[36mPath2: [94m/tmp/085741di/workdir/TestOpenDrive_rclone-test-puvulez9gomu_085741di_filtersfile_checks_path1..tmp_085741di_filtersfile_checks_path2.path2.lst[0m
[0m[35mTry running this command to inspect the work dir:
[0m[96mrclone lsl "/tmp/085741di/workdir"[0m[0m
2025/03/06 08:57:56 ERROR : [31mBisync aborted. Must run --resync to recover.[0m
2025/03/06 08:57:56 NOTICE: Bisync error: bisync aborted
2025/03/06 08:57:56 NOTICE: [36m(10) :[0m [34mtest 4. run with filters-file and resync.[0m
2025/03/06 08:57:56 NOTICE: [36m(11) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt resync[0m
2025/03/06 08:57:59 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:59 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:57:59 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:57:59 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:59 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:57:59 INFO : Storing filters file hash to /tmp/085741di/workdir/filtersfile.txt.md5
2025/03/06 08:57:59 INFO : Copying Path2 files to Path1
2025/03/06 08:57:59 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:57:59 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:57:59 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:57:59 INFO : Resync updating listings
2025/03/06 08:57:59 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" vs Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:57:59 INFO : [32mBisync successful[0m
2025/03/06 08:57:59 NOTICE: [36m(12) :[0m [34mtest 5. run with filters-file alone. should run.[0m
2025/03/06 08:57:59 NOTICE: [36m(13) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:58:01 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:01 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:01 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:01 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:58:01 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:58:01 INFO : Building Path1 and Path2 listings
2025/03/06 08:58:02 INFO : Path1 checking for diffs
2025/03/06 08:58:02 INFO : Path2 checking for diffs
2025/03/06 08:58:02 INFO : No changes found
2025/03/06 08:58:02 INFO : Updating listings
2025/03/06 08:58:02 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" vs Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:58:02 INFO : [32mBisync successful[0m
2025/03/06 08:58:02 NOTICE: [36m(14) :[0m [34mtest 6. push changed filters-file to workdir.[0m
2025/03/06 08:58:02 NOTICE: [36m(15) :[0m [34mcopy-as /tmp/085741di/datadir/test_filtersfile_checks-zagofar9/filtersfile2.txt /tmp/085741di/workdir/ filtersfile.txt[0m
2025/03/06 08:58:02 INFO : filtersfile2.txt: Copied (replaced existing) to: filtersfile.txt
2025/03/06 08:58:02 NOTICE: [36m(16) :[0m [34mtest 7. run with filters-file alone. should abort.[0m
2025/03/06 08:58:02 NOTICE: [36m(17) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:58:04 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:04 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:04 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:04 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:58:04 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:58:04 ERROR : [31mBisync critical error: filters file has changed (must run --resync): /tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:58:04 ERROR : [31mBisync aborted. Must run --resync to recover.[0m
2025/03/06 08:58:04 NOTICE: Bisync error: bisync aborted
2025/03/06 08:58:04 NOTICE: [36m(18) :[0m [34mtest 8. run with filters-file and resync and dry-run. should do the dry-run but still cause next non-resync run to abort.[0m
2025/03/06 08:58:04 NOTICE: [36m(19) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt resync dry-run[0m
2025/03/06 08:58:06 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:06 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:06 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:06 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:58:06 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:58:06 INFO : Skipped storing filters file hash to /tmp/085741di/workdir/filtersfile.txt.md5 as --dry-run is set
2025/03/06 08:58:06 INFO : Copying Path2 files to Path1
2025/03/06 08:58:06 NOTICE: - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:58:06 NOTICE: - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:58:06 NOTICE: subdir: Skipped set directory modification time as --dry-run is set (size 4Ki)
2025/03/06 08:58:07 INFO : Resync updating listings
2025/03/06 08:58:07 INFO : [32mBisync successful[0m
2025/03/06 08:58:07 NOTICE: [36m(20) :[0m [34mtest 9. run with filters-file alone. should abort.[0m
2025/03/06 08:58:07 NOTICE: [36m(21) :[0m [34mbisync filters-file=/tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:58:09 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:09 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:09 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:09 INFO : Synching Path1 "TestOpenDrive:rclone-test-puvulez9gomu/085741di/filtersfile_checks/path1/" with Path2 "/tmp/085741di/filtersfile_checks/path2/"
2025/03/06 08:58:09 INFO : Using filters file /tmp/085741di/workdir/filtersfile.txt
2025/03/06 08:58:09 ERROR : [31mBisync critical error: filters file has changed (must run --resync): /tmp/085741di/workdir/filtersfile.txt[0m
2025/03/06 08:58:09 ERROR : [31mBisync aborted. Must run --resync to recover.[0m
2025/03/06 08:58:09 NOTICE: Bisync error: bisync aborted
bisync_test.go:560: [32mTEST filtersfile_checks PASSED[0m
2025/03/06 08:58:09 DEBUG : OpenDrive root 'rclone-test-puvulez9gomu': Purge remote
--- PASS: TestBisyncRemoteLocal (30.04s)
--- PASS: TestBisyncRemoteLocal/filtersfile_checks (28.65s)
=== RUN TestBisyncLocalRemote
2025/03/06 08:58:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vumonuc9nava"
2025/03/06 08:58:10 DEBUG : Starting OpenDrive session with ID: c86300389671fd61b7447b03fa0b18232ee3fa20da06ad88593c592626020205
2025/03/06 08:58:11 NOTICE: remote: TestOpenDrive:rclone-test-vumonuc9nava
=== RUN TestBisyncLocalRemote/rclone_args
2025/03/06 08:58:11 DEBUG : Creating backend with remote "/tmp/085811va"
2025/03/06 08:58:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/06 08:58:11 DEBUG : Creating backend with remote "/tmp/085811va/rclone_args/path1"
2025/03/06 08:58:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vumonuc9nava/085811va"
2025/03/06 08:58:11 DEBUG : Starting OpenDrive session with ID: 995d8afaa265b63756ea790e1a794f8a350f48210b31cb881903dfe7ec81cdbb
2025/03/06 08:58:11 INFO : path2: Making directory
2025/03/06 08:58:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2"
2025/03/06 08:58:13 DEBUG : Starting OpenDrive session with ID: cf3225724ec146b0c69ae3bb4adb873b4ce0f4069775ea8bc99a8563508810ce
2025/03/06 08:58:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/initial"
2025/03/06 08:58:14 DEBUG : Creating backend with remote "/tmp/085811va/initdir/test_rclone_args-ganudug0"
2025/03/06 08:58:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : subdir: Making directory with metadata
2025/03/06 08:58:14 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/06 08:58:14 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/06 08:58:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/initdir/test_rclone_args-ganudug0: Waiting for checks to finish
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/initdir/test_rclone_args-ganudug0: Waiting for transfers to finish
2025/03/06 08:58:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 08:58:14 INFO : file2.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/06 08:58:14 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : subdir/file21.txt.5028a97.partial: renamed to: subdir/file21.txt
2025/03/06 08:58:14 INFO : subdir/file21.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/03/06 08:58:14 DEBUG : file1.txt.dae86b42.partial: renamed to: file1.txt
2025/03/06 08:58:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:58:14 INFO : file1.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 08:58:14 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:58:14 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/modfiles"
2025/03/06 08:58:14 DEBUG : Creating backend with remote "/tmp/085811va/datadir/test_rclone_args-lugucor1"
2025/03/06 08:58:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file21.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/datadir/test_rclone_args-lugucor1: Waiting for checks to finish
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/datadir/test_rclone_args-lugucor1: Waiting for transfers to finish
2025/03/06 08:58:14 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : file20.txt.b4075ff1.partial: renamed to: file20.txt
2025/03/06 08:58:14 INFO : file20.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : file21.txt.b4075ff1.partial: renamed to: file21.txt
2025/03/06 08:58:14 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 08:58:14 INFO : file21.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : file1.txt.6bedbe24.partial: renamed to: file1.txt
2025/03/06 08:58:14 INFO : file1.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 08:58:14 DEBUG : file2.txt.6bedbe24.partial: renamed to: file2.txt
2025/03/06 08:58:14 INFO : file2.txt: Copied (new)
2025/03/06 08:58:14 ERROR : : error listing: directory not found
2025/03/06 08:58:14 ERROR : : error listing: directory not found
2025/03/06 08:58:14 NOTICE: checking initFs Local file system at /tmp/085811va/initdir/test_rclone_args-ganudug0
2025/03/06 08:58:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : subdir: Making directory with metadata
2025/03/06 08:58:14 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/06 08:58:14 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/06 08:58:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 08:58:14 INFO : file2.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/rclone_args/path1: Waiting for checks to finish
2025/03/06 08:58:14 DEBUG : Local file system at /tmp/085811va/rclone_args/path1: Waiting for transfers to finish
2025/03/06 08:58:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:58:14 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/06 08:58:14 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : subdir/file21.txt.5028a97.partial: renamed to: subdir/file21.txt
2025/03/06 08:58:14 INFO : subdir/file21.txt: Copied (new)
2025/03/06 08:58:14 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 08:58:14 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:58:14 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/03/06 08:58:14 DEBUG : file1.txt.dae86b42.partial: renamed to: file1.txt
2025/03/06 08:58:14 INFO : file1.txt: Copied (new)
2025/03/06 08:58:14 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:14 NOTICE: checking Path1 Local file system at /tmp/085811va/rclone_args/path1
2025/03/06 08:58:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:15 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 08:58:15 INFO : subdir: Making directory
2025/03/06 08:58:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:16 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/03/06 08:58:16 DEBUG : OpenDrive root 'rclone-test-vumonuc9nava/085811va/rclone_args/path2': Waiting for checks to finish
2025/03/06 08:58:16 DEBUG : OpenDrive root 'rclone-test-vumonuc9nava/085811va/rclone_args/path2': Waiting for transfers to finish
2025/03/06 08:58:17 DEBUG : file1.txt: Uploading chunk 0, size=19, remain=0
2025/03/06 08:58:17 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 08:58:17 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/03/06 08:58:17 INFO : file1.txt: Copied (new)
2025/03/06 08:58:18 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:18 INFO : subdir/file20.txt: Copied (new)
2025/03/06 08:58:18 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:58:18 INFO : RCLONE_TEST: Copied (new)
2025/03/06 08:58:18 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:18 INFO : file2.txt: Copied (new)
2025/03/06 08:58:20 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:58:20 INFO : subdir/file21.txt: Copied (new)
2025/03/06 08:58:20 NOTICE: checking path2 OpenDrive root 'rclone-test-vumonuc9nava/085811va/rclone_args/path2'
2025/03/06 08:58:20 NOTICE: [36m(01) :[0m [34mtest rclone-args[0m
2025/03/06 08:58:20 NOTICE: [36m(02) :[0m [34mtouch-glob 2001-01-02 /tmp/085811va/datadir/test_rclone_args-lugucor1/ *[0m
2025/03/06 08:58:20 NOTICE: [36m(03) :[0m [34mtest initial bisync[0m
2025/03/06 08:58:20 NOTICE: [36m(04) :[0m [34mbisync resync checksum[0m
2025/03/06 08:58:22 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:22 INFO : Bisyncing with Comparison Settings:
{
"Modtime": false,
"Size": true,
"Checksum": true,
"HashType1": 1,
"HashType2": 1,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": true
}
2025/03/06 08:58:22 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:22 INFO : Copying Path2 files to Path1
2025/03/06 08:58:22 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:58:22 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:58:23 INFO : Resync updating listings
2025/03/06 08:58:23 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:23 INFO : [32mBisync successful[0m
2025/03/06 08:58:23 NOTICE: [36m(05) :[0m [34mtest place newer files on both paths[0m
2025/03/06 08:58:23 NOTICE: [36m(06) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file1.txt /tmp/085811va/rclone_args/path1/[0m
2025/03/06 08:58:23 INFO : file1.txt: Copied (replaced existing)
2025/03/06 08:58:23 NOTICE: [36m(07) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file2.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/[0m
2025/03/06 08:58:25 INFO : file2.txt: Copied (replaced existing)
2025/03/06 08:58:25 NOTICE: [36m(08) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file20.txt /tmp/085811va/rclone_args/path1/subdir[0m
2025/03/06 08:58:25 INFO : file20.txt: Updated modification time in destination
2025/03/06 08:58:25 NOTICE: [36m(09) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file21.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir[0m
2025/03/06 08:58:28 INFO : file21.txt: Copied (replaced existing)
2025/03/06 08:58:28 NOTICE: [36m(10) :[0m [34mtouch-glob 2001-01-02 /tmp/085811va/rclone_args/path1/ file1.txt[0m
2025/03/06 08:58:28 NOTICE: [36m(11) :[0m [34mtouch-glob 2001-01-02 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file2.txt[0m
2025/03/06 08:58:29 NOTICE: [36m(12) :[0m [34mtouch-glob 2001-01-02 /tmp/085811va/rclone_args/path1/subdir/ file20.txt[0m
2025/03/06 08:58:29 NOTICE: [36m(13) :[0m [34mtouch-glob 2001-01-02 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir/ file21.txt[0m
2025/03/06 08:58:31 NOTICE: [36m(14) :[0m [34mtest run bisync with custom options[0m
2025/03/06 08:58:31 NOTICE: [36m(15) :[0m [34mbisync checksum[0m
2025/03/06 08:58:33 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:33 INFO : Bisyncing with Comparison Settings:
{
"Modtime": false,
"Size": true,
"Checksum": true,
"HashType1": 1,
"HashType2": 1,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": true
}
2025/03/06 08:58:33 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:33 INFO : Building Path1 and Path2 listings
2025/03/06 08:58:33 INFO : Path1 checking for diffs
2025/03/06 08:58:33 INFO : - [36mPath1[0m [35m[33mFile changed: [35mhash[0m[0m[0m - [36mfile1.txt[0m
2025/03/06 08:58:33 INFO : Path1: 1 changes: [32m 0 new[0m, [33m 1 modified[0m, [31m 0 deleted[0m
2025/03/06 08:58:33 INFO : ([33mModified[0m: [36m 1 hash differs[0m)
2025/03/06 08:58:33 INFO : Path2 checking for diffs
2025/03/06 08:58:33 INFO : - [34mPath2[0m [35m[33mFile changed: [35msize (larger)[0m, [35mhash[0m[0m[0m - [36mfile2.txt[0m
2025/03/06 08:58:33 INFO : Path2: 1 changes: [32m 0 new[0m, [33m 1 modified[0m, [31m 0 deleted[0m
2025/03/06 08:58:33 INFO : ([33mModified[0m: [36m 1 larger[0m, [34m 0 smaller[0m, [36m 1 hash differs[0m)
2025/03/06 08:58:33 INFO : Applying changes
2025/03/06 08:58:33 INFO : - [36mPath1[0m [35m[32mQueue copy to[0m Path2[0m - [36mTestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/file1.txt[0m
2025/03/06 08:58:33 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/file2.txt[0m
2025/03/06 08:58:33 INFO : - [34mPath2[0m [35mDo queued copies to[0m - [36mPath1[0m
2025/03/06 08:58:34 INFO : file2.txt: Copied (replaced existing)
2025/03/06 08:58:34 INFO : - [36mPath1[0m [35mDo queued copies to[0m - [36mPath2[0m
2025/03/06 08:58:35 INFO : file1.txt: Copied (replaced existing)
2025/03/06 08:58:35 INFO : Updating listings
2025/03/06 08:58:35 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:35 INFO : [32mBisync successful[0m
2025/03/06 08:58:35 NOTICE: [36m(16) :[0m [34mtouch-glob 2007-07-23 /tmp/085811va/datadir/test_rclone_args-lugucor1/ *[0m
2025/03/06 08:58:35 NOTICE: [36m(17) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file1.txt /tmp/085811va/rclone_args/path1/[0m
2025/03/06 08:58:35 INFO : file1.txt: Updated modification time in destination
2025/03/06 08:58:35 NOTICE: [36m(18) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file2.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/[0m
2025/03/06 08:58:38 INFO : file2.txt: Copied (replaced existing)
2025/03/06 08:58:38 NOTICE: [36m(19) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file20.txt /tmp/085811va/rclone_args/path1/subdir[0m
2025/03/06 08:58:38 INFO : file20.txt: Updated modification time in destination
2025/03/06 08:58:38 NOTICE: [36m(20) :[0m [34mcopy-as /tmp/085811va/datadir/test_rclone_args-lugucor1/file21.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file1.txt[0m
2025/03/06 08:58:41 INFO : file21.txt: Copied (replaced existing) to: file1.txt
2025/03/06 08:58:41 NOTICE: [36m(21) :[0m [34mtouch-glob 2007-07-23 /tmp/085811va/rclone_args/path1/ file1.txt[0m
2025/03/06 08:58:41 NOTICE: [36m(22) :[0m [34mtouch-glob 2007-07-23 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file2.txt[0m
2025/03/06 08:58:42 NOTICE: [36m(23) :[0m [34mtouch-glob 2007-07-23 /tmp/085811va/rclone_args/path1/subdir/ file20.txt[0m
2025/03/06 08:58:42 NOTICE: [36m(24) :[0m [34mtouch-glob 2007-07-23 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir/ file21.txt[0m
2025/03/06 08:58:43 NOTICE: [36m(25) :[0m [34mbisync size-only[0m
2025/03/06 08:58:45 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:45 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:45 INFO : Bisyncing with Comparison Settings:
{
"Modtime": false,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:45 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:45 INFO : Building Path1 and Path2 listings
2025/03/06 08:58:45 INFO : Path1 checking for diffs
2025/03/06 08:58:45 INFO : Path2 checking for diffs
2025/03/06 08:58:45 INFO : - [34mPath2[0m [35m[33mFile changed: [35msize (smaller)[0m[0m[0m - [36mfile1.txt[0m
2025/03/06 08:58:45 INFO : Path2: 1 changes: [32m 0 new[0m, [33m 1 modified[0m, [31m 0 deleted[0m
2025/03/06 08:58:45 INFO : ([33mModified[0m: [36m 0 larger[0m, [34m 1 smaller[0m)
2025/03/06 08:58:45 INFO : Applying changes
2025/03/06 08:58:45 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/file1.txt[0m
2025/03/06 08:58:45 INFO : - [34mPath2[0m [35mDo queued copies to[0m - [36mPath1[0m
2025/03/06 08:58:46 INFO : file1.txt: Copied (replaced existing)
2025/03/06 08:58:46 INFO : Updating listings
2025/03/06 08:58:46 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:46 INFO : [32mBisync successful[0m
2025/03/06 08:58:46 NOTICE: [36m(26) :[0m [34mbisync resync[0m
2025/03/06 08:58:48 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:48 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:48 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:48 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:48 INFO : Copying Path2 files to Path1
2025/03/06 08:58:48 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:58:48 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:58:49 INFO : file2.txt: Updated modification time in destination
2025/03/06 08:58:49 INFO : subdir/file21.txt: Updated modification time in destination
2025/03/06 08:58:50 INFO : subdir/file20.txt: Updated modification time in destination
2025/03/06 08:58:50 INFO : Resync updating listings
2025/03/06 08:58:50 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:50 INFO : [32mBisync successful[0m
2025/03/06 08:58:50 NOTICE: [36m(27) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file1.txt /tmp/085811va/rclone_args/path1/[0m
2025/03/06 08:58:50 INFO : file1.txt: Copied (replaced existing)
2025/03/06 08:58:50 NOTICE: [36m(28) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file2.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/[0m
2025/03/06 08:58:53 INFO : file2.txt: Copied (replaced existing)
2025/03/06 08:58:53 NOTICE: [36m(29) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file20.txt /tmp/085811va/rclone_args/path1/subdir[0m
2025/03/06 08:58:53 NOTICE: [36m(30) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file21.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir[0m
2025/03/06 08:58:56 INFO : file21.txt: Copied (replaced existing)
2025/03/06 08:58:56 NOTICE: [36m(31) :[0m [34mtouch-glob 2007-07-23 /tmp/085811va/rclone_args/path1/ file1.txt[0m
2025/03/06 08:58:56 NOTICE: [36m(32) :[0m [34mtouch-glob 2007-07-23 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file2.txt[0m
2025/03/06 08:58:56 NOTICE: [36m(33) :[0m [34mtouch-glob 2007-07-23 /tmp/085811va/rclone_args/path1/subdir/ file20.txt[0m
2025/03/06 08:58:56 NOTICE: [36m(34) :[0m [34mtouch-glob 2007-07-23 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir/ file21.txt[0m
2025/03/06 08:58:57 NOTICE: [36m(35) :[0m [34mbisync ignore-size[0m
2025/03/06 08:58:59 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:59 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:58:59 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": false,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": false
}
2025/03/06 08:58:59 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:59 INFO : Building Path1 and Path2 listings
2025/03/06 08:58:59 INFO : Path1 checking for diffs
2025/03/06 08:58:59 INFO : Path2 checking for diffs
2025/03/06 08:58:59 INFO : - [34mPath2[0m [35m[33mFile changed: [35mtime (newer)[0m[0m[0m - [36mfile2.txt[0m
2025/03/06 08:58:59 INFO : - [34mPath2[0m [35m[33mFile changed: [35mtime (newer)[0m[0m[0m - [36msubdir/file21.txt[0m
2025/03/06 08:58:59 INFO : Path2: 2 changes: [32m 0 new[0m, [33m 2 modified[0m, [31m 0 deleted[0m
2025/03/06 08:58:59 INFO : ([33mModified[0m: [36m 2 newer[0m, [34m 0 older[0m)
2025/03/06 08:58:59 INFO : Applying changes
2025/03/06 08:58:59 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/file2.txt[0m
2025/03/06 08:58:59 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/subdir/file21.txt[0m
2025/03/06 08:58:59 INFO : - [34mPath2[0m [35mDo queued copies to[0m - [36mPath1[0m
2025/03/06 08:58:59 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:58:59 INFO : file2.txt: Updated modification time in destination
2025/03/06 08:58:59 INFO : subdir/file21.txt: Updated modification time in destination
2025/03/06 08:58:59 INFO : Updating listings
2025/03/06 08:58:59 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:58:59 INFO : [32mBisync successful[0m
2025/03/06 08:58:59 NOTICE: [36m(36) :[0m [34mbisync resync compare-all[0m
2025/03/06 08:59:01 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:59:01 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": true,
"HashType1": 1,
"HashType2": 1,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": true
}
2025/03/06 08:59:01 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:59:01 INFO : Copying Path2 files to Path1
2025/03/06 08:59:01 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:59:02 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:59:03 INFO : file1.txt: Copied (replaced existing)
2025/03/06 08:59:03 INFO : Resync updating listings
2025/03/06 08:59:03 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:59:03 INFO : [32mBisync successful[0m
2025/03/06 08:59:03 NOTICE: [36m(37) :[0m [34mcopy-as /tmp/085811va/datadir/test_rclone_args-lugucor1/file21.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file2.txt[0m
2025/03/06 08:59:06 INFO : file21.txt: Copied (replaced existing) to: file2.txt
2025/03/06 08:59:06 NOTICE: [36m(38) :[0m [34mtouch-glob 2023-08-26 /tmp/085811va/datadir/test_rclone_args-lugucor1/ *[0m
2025/03/06 08:59:06 NOTICE: [36m(39) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file1.txt /tmp/085811va/rclone_args/path1/[0m
2025/03/06 08:59:06 INFO : file1.txt: Updated modification time in destination
2025/03/06 08:59:06 NOTICE: [36m(40) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file20.txt /tmp/085811va/rclone_args/path1/subdir[0m
2025/03/06 08:59:06 INFO : file20.txt: Updated modification time in destination
2025/03/06 08:59:06 NOTICE: [36m(41) :[0m [34mcopy-file /tmp/085811va/datadir/test_rclone_args-lugucor1/file21.txt TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir[0m
2025/03/06 08:59:08 INFO : file21.txt: Copied (replaced existing)
2025/03/06 08:59:08 NOTICE: [36m(42) :[0m [34mtouch-glob 2007-07-23 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/ file2.txt[0m
2025/03/06 08:59:09 NOTICE: [36m(43) :[0m [34mtouch-glob 2023-08-26 /tmp/085811va/rclone_args/path1/ file1.txt[0m
2025/03/06 08:59:09 NOTICE: [36m(44) :[0m [34mtouch-glob 2023-08-26 /tmp/085811va/rclone_args/path1/subdir/ file20.txt[0m
2025/03/06 08:59:09 NOTICE: [36m(45) :[0m [34mtouch-glob 2023-08-26 TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir/ file21.txt[0m
2025/03/06 08:59:09 NOTICE: [36m(46) :[0m [34mbisync compare-all[0m
2025/03/06 08:59:11 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:59:11 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": true,
"HashType1": 1,
"HashType2": 1,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": true,
"DownloadHash": true
}
2025/03/06 08:59:11 INFO : Synching Path1 "/tmp/085811va/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:59:11 INFO : Building Path1 and Path2 listings
2025/03/06 08:59:11 INFO : Path1 checking for diffs
2025/03/06 08:59:11 INFO : - [36mPath1[0m [35m[33mFile changed: [35mtime (newer)[0m[0m[0m - [36mfile1.txt[0m
2025/03/06 08:59:11 INFO : - [36mPath1[0m [35m[33mFile changed: [35mtime (newer)[0m[0m[0m - [36msubdir/file20.txt[0m
2025/03/06 08:59:11 INFO : Path1: 2 changes: [32m 0 new[0m, [33m 2 modified[0m, [31m 0 deleted[0m
2025/03/06 08:59:11 INFO : ([33mModified[0m: [36m 2 newer[0m, [34m 0 older[0m)
2025/03/06 08:59:11 INFO : Path2 checking for diffs
2025/03/06 08:59:11 INFO : - [34mPath2[0m [35m[33mFile changed: [35msize (smaller)[0m, [35mhash[0m[0m[0m - [36mfile2.txt[0m
2025/03/06 08:59:11 INFO : - [34mPath2[0m [35m[33mFile changed: [35mtime (newer)[0m[0m[0m - [36msubdir/file21.txt[0m
2025/03/06 08:59:11 INFO : Path2: 2 changes: [32m 0 new[0m, [33m 2 modified[0m, [31m 0 deleted[0m
2025/03/06 08:59:11 INFO : ([33mModified[0m: [36m 1 newer[0m, [34m 0 older[0m, [36m 0 larger[0m, [34m 1 smaller[0m, [36m 1 hash differs[0m)
2025/03/06 08:59:11 INFO : Applying changes
2025/03/06 08:59:11 INFO : - [36mPath1[0m [35m[32mQueue copy to[0m Path2[0m - [36mTestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/file1.txt[0m
2025/03/06 08:59:11 INFO : - [36mPath1[0m [35m[32mQueue copy to[0m Path2[0m - [36mTestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/subdir/file20.txt[0m
2025/03/06 08:59:11 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/file2.txt[0m
2025/03/06 08:59:11 INFO : - [34mPath2[0m [35m[32mQueue copy to[0m Path1[0m - [36m/tmp/085811va/rclone_args/path1/subdir/file21.txt[0m
2025/03/06 08:59:11 INFO : - [34mPath2[0m [35mDo queued copies to[0m - [36mPath1[0m
2025/03/06 08:59:12 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 08:59:12 INFO : subdir/file21.txt: Updated modification time in destination
2025/03/06 08:59:12 INFO : file2.txt: Copied (replaced existing)
2025/03/06 08:59:12 INFO : - [36mPath1[0m [35mDo queued copies to[0m - [36mPath2[0m
2025/03/06 08:59:13 INFO : file1.txt: Updated modification time in destination
2025/03/06 08:59:13 INFO : subdir/file20.txt: Updated modification time in destination
2025/03/06 08:59:13 INFO : Updating listings
2025/03/06 08:59:13 INFO : Validating listings for Path1 "/tmp/085811va/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-vumonuc9nava/085811va/rclone_args/path2/"
2025/03/06 08:59:13 INFO : [32mBisync successful[0m
bisync_test.go:560: [32mTEST rclone_args PASSED[0m
2025/03/06 08:59:13 DEBUG : OpenDrive root 'rclone-test-vumonuc9nava': Purge remote
--- PASS: TestBisyncLocalRemote (64.32s)
--- PASS: TestBisyncLocalRemote/rclone_args (62.64s)
=== RUN TestBisyncRemoteRemote
2025/03/06 08:59:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva"
2025/03/06 08:59:15 DEBUG : Starting OpenDrive session with ID: 4baa01405b5d7f115d99616720e6f77dde221a8aaec14689538506bf4dee4dd2
2025/03/06 08:59:15 NOTICE: remote: TestOpenDrive:rclone-test-dohayuq1deva
=== RUN TestBisyncRemoteRemote/max_delete_path2_force
2025/03/06 08:59:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi"
2025/03/06 08:59:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/06 08:59:15 DEBUG : Starting OpenDrive session with ID: 0d8b3e1797b8bbb1017656d44da5ce02e123192097c94f7c074d38255934953a
2025/03/06 08:59:16 INFO : path1: Making directory
2025/03/06 08:59:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1"
2025/03/06 08:59:17 DEBUG : Starting OpenDrive session with ID: e036780e03d03112efc39c2d26d9251b72551a3047909dcec3ba18098d3b30d5
2025/03/06 08:59:18 INFO : path2: Making directory
2025/03/06 08:59:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2"
2025/03/06 08:59:19 DEBUG : Starting OpenDrive session with ID: 6f504986ee1155cc2a2ee4127c0ebc0c3ee1c99171fd5d97c69b2d42f696f200
2025/03/06 08:59:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/initial"
2025/03/06 08:59:20 DEBUG : Creating backend with remote "/tmp/085915zi/initdir/test_max_delete_path2_force-jeyumib5"
2025/03/06 08:59:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : Local file system at /tmp/085915zi/initdir/test_max_delete_path2_force-jeyumib5: Waiting for checks to finish
2025/03/06 08:59:20 DEBUG : Local file system at /tmp/085915zi/initdir/test_max_delete_path2_force-jeyumib5: Waiting for transfers to finish
2025/03/06 08:59:20 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/06 08:59:20 INFO : file1.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 08:59:20 INFO : file2.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/03/06 08:59:20 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 INFO : file3.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/03/06 08:59:20 INFO : file4.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/03/06 08:59:20 INFO : file5.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/03/06 08:59:20 INFO : file6.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/03/06 08:59:20 INFO : file7.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:20 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/03/06 08:59:20 INFO : file8.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:59:20 DEBUG : file9.txt.cf9a93ac.partial: renamed to: file9.txt
2025/03/06 08:59:20 INFO : file9.txt: Copied (new)
2025/03/06 08:59:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/modfiles"
2025/03/06 08:59:20 DEBUG : Creating backend with remote "/tmp/085915zi/datadir/test_max_delete_path2_force-gihixim4"
2025/03/06 08:59:20 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:20 DEBUG : Local file system at /tmp/085915zi/datadir/test_max_delete_path2_force-gihixim4: Waiting for checks to finish
2025/03/06 08:59:20 DEBUG : Local file system at /tmp/085915zi/datadir/test_max_delete_path2_force-gihixim4: Waiting for transfers to finish
2025/03/06 08:59:20 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/03/06 08:59:20 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/03/06 08:59:20 INFO : hold.txt: Copied (new)
2025/03/06 08:59:21 ERROR : : error listing: directory not found
2025/03/06 08:59:21 ERROR : : error listing: directory not found
2025/03/06 08:59:21 NOTICE: checking initFs Local file system at /tmp/085915zi/initdir/test_max_delete_path2_force-jeyumib5
2025/03/06 08:59:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:21 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1': Waiting for checks to finish
2025/03/06 08:59:21 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1': Waiting for transfers to finish
2025/03/06 08:59:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:24 INFO : file3.txt: Copied (new)
2025/03/06 08:59:24 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:24 INFO : file2.txt: Copied (new)
2025/03/06 08:59:24 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:24 INFO : file4.txt: Copied (new)
2025/03/06 08:59:27 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:27 INFO : file1.txt: Copied (new)
2025/03/06 08:59:28 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:28 INFO : file6.txt: Copied (new)
2025/03/06 08:59:28 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:28 INFO : file5.txt: Copied (new)
2025/03/06 08:59:28 DEBUG : file9.txt: Uploading chunk 0, size=109, remain=0
2025/03/06 08:59:29 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:29 INFO : file7.txt: Copied (new)
2025/03/06 08:59:29 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:59:29 INFO : file9.txt: Copied (new)
2025/03/06 08:59:29 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:29 INFO : file8.txt: Copied (new)
2025/03/06 08:59:29 NOTICE: checking Path1 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1'
2025/03/06 08:59:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/03/06 08:59:30 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2': Waiting for checks to finish
2025/03/06 08:59:30 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2': Waiting for transfers to finish
2025/03/06 08:59:32 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:32 INFO : file1.txt: Copied (new)
2025/03/06 08:59:32 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:32 INFO : file3.txt: Copied (new)
2025/03/06 08:59:33 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:33 INFO : file2.txt: Copied (new)
2025/03/06 08:59:33 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:33 INFO : file5.txt: Copied (new)
2025/03/06 08:59:34 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:34 INFO : file6.txt: Copied (new)
2025/03/06 08:59:35 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:35 INFO : file4.txt: Copied (new)
2025/03/06 08:59:35 DEBUG : file9.txt: Uploading chunk 0, size=109, remain=0
2025/03/06 08:59:35 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:35 INFO : file8.txt: Copied (new)
2025/03/06 08:59:35 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 08:59:35 INFO : file7.txt: Copied (new)
2025/03/06 08:59:36 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 08:59:36 INFO : file9.txt: Copied (new)
2025/03/06 08:59:36 NOTICE: checking path2 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2'
2025/03/06 08:59:36 NOTICE: [36m(01) :[0m [34mtest max-delete-path2-force[0m
2025/03/06 08:59:36 NOTICE: [36m(02) :[0m [34mtest initial bisync[0m
2025/03/06 08:59:36 NOTICE: [36m(03) :[0m [34mbisync resync[0m
2025/03/06 08:59:40 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:59:40 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 08:59:40 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/"
2025/03/06 08:59:40 INFO : Copying Path2 files to Path1
2025/03/06 08:59:40 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 08:59:41 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 08:59:42 INFO : Resync updating listings
2025/03/06 08:59:42 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/" vs Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/"
2025/03/06 08:59:42 INFO : [32mBisync successful[0m
2025/03/06 08:59:42 NOTICE: [36m(04) :[0m [34mtest delete >50% of remote files[0m
2025/03/06 08:59:42 NOTICE: [36m(05) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/file1.txt[0m
2025/03/06 08:59:44 INFO : file1.txt: Deleted
2025/03/06 08:59:44 NOTICE: [36m(06) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/file2.txt[0m
2025/03/06 08:59:44 INFO : file2.txt: Deleted
2025/03/06 08:59:44 NOTICE: [36m(07) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/file3.txt[0m
2025/03/06 08:59:44 INFO : file3.txt: Deleted
2025/03/06 08:59:44 NOTICE: [36m(08) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/file4.txt[0m
2025/03/06 08:59:45 INFO : file4.txt: Deleted
2025/03/06 08:59:45 NOTICE: [36m(09) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/file5.txt[0m
2025/03/06 08:59:45 INFO : file5.txt: Deleted
2025/03/06 08:59:45 NOTICE: [36m(10) :[0m [34mtest sync should fail due to too many path2 deletes[0m
2025/03/06 08:59:45 NOTICE: [36m(11) :[0m [34mbisync[0m
2025/03/06 08:59:50 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:59:50 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 08:59:50 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/"
2025/03/06 08:59:50 INFO : Building Path1 and Path2 listings
2025/03/06 08:59:51 INFO : Path1 checking for diffs
2025/03/06 08:59:51 INFO : Path2 checking for diffs
2025/03/06 08:59:51 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile1.txt[0m
2025/03/06 08:59:51 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile2.txt[0m
2025/03/06 08:59:51 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile3.txt[0m
2025/03/06 08:59:51 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile4.txt[0m
2025/03/06 08:59:51 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile5.txt[0m
2025/03/06 08:59:51 INFO : Path2: 5 changes: [32m 0 new[0m, [33m 0 modified[0m, [31m 5 deleted[0m
2025/03/06 08:59:51 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/". Run with --force if desired.
2025/03/06 08:59:51 NOTICE: [31mBisync aborted. Please try again.[0m
2025/03/06 08:59:51 NOTICE: Bisync error: too many deletes
2025/03/06 08:59:51 NOTICE: [36m(12) :[0m [34mcopy-listings initial-fail[0m
2025/03/06 08:59:51 NOTICE: [36m(13) :[0m [34mtest apply force option. sync should run.[0m
2025/03/06 08:59:51 NOTICE: [36m(14) :[0m [34mbisync force[0m
2025/03/06 08:59:55 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 08:59:55 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 08:59:55 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/"
2025/03/06 08:59:55 INFO : Building Path1 and Path2 listings
2025/03/06 08:59:55 INFO : Path1 checking for diffs
2025/03/06 08:59:55 INFO : Path2 checking for diffs
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile1.txt[0m
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile2.txt[0m
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile3.txt[0m
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile4.txt[0m
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35m[31mFile was deleted[0m[0m - [36mfile5.txt[0m
2025/03/06 08:59:55 INFO : Path2: 5 changes: [32m 0 new[0m, [33m 0 modified[0m, [31m 5 deleted[0m
2025/03/06 08:59:55 INFO : Applying changes
2025/03/06 08:59:55 INFO : - [36mPath1[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/file1.txt[0m
2025/03/06 08:59:55 INFO : - [36mPath1[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/file2.txt[0m
2025/03/06 08:59:55 INFO : - [36mPath1[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/file3.txt[0m
2025/03/06 08:59:55 INFO : - [36mPath1[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/file4.txt[0m
2025/03/06 08:59:55 INFO : - [36mPath1[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/file5.txt[0m
2025/03/06 08:59:55 INFO : - [34mPath2[0m [35mDo queued copies to[0m - [36mPath1[0m
2025/03/06 08:59:56 INFO : file1.txt: Deleted
2025/03/06 08:59:56 INFO : file5.txt: Deleted
2025/03/06 08:59:56 INFO : file3.txt: Deleted
2025/03/06 08:59:56 INFO : file4.txt: Deleted
2025/03/06 08:59:56 INFO : file2.txt: Deleted
2025/03/06 08:59:56 INFO : Updating listings
2025/03/06 08:59:56 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path1/" vs Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/max_delete_path2_force/path2/"
2025/03/06 08:59:56 INFO : [32mBisync successful[0m
bisync_test.go:560: [32mTEST max_delete_path2_force PASSED[0m
=== RUN TestBisyncRemoteRemote/rmdirs
2025/03/06 08:59:56 INFO : path1: Making directory
2025/03/06 08:59:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1"
2025/03/06 08:59:57 DEBUG : Starting OpenDrive session with ID: 6597f866876d9bf15edb80a670392029c9bfbf0a946773233d954c804f6264d6
2025/03/06 08:59:58 INFO : path2: Making directory
2025/03/06 08:59:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2"
2025/03/06 08:59:58 DEBUG : Starting OpenDrive session with ID: ce0bdba4b450f4e1ea569ba12684506190de0b110c6f1c0abd121bfaf4fdb3a7
2025/03/06 09:00:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/03/06 09:00:00 DEBUG : Creating backend with remote "/tmp/085915zi/initdir/test_rmdirs-qizuxah8"
2025/03/06 09:00:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : subdir: Making directory with metadata
2025/03/06 09:00:00 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/06 09:00:00 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/06 09:00:00 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : Local file system at /tmp/085915zi/initdir/test_rmdirs-qizuxah8: Waiting for checks to finish
2025/03/06 09:00:00 DEBUG : Local file system at /tmp/085915zi/initdir/test_rmdirs-qizuxah8: Waiting for transfers to finish
2025/03/06 09:00:00 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/03/06 09:00:00 INFO : file1.copy2.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/03/06 09:00:00 INFO : file1.copy3.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/03/06 09:00:00 INFO : file1.copy1.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:00:00 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 09:00:00 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:00:00 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/03/06 09:00:00 INFO : file1.copy4.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:00 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/03/06 09:00:00 INFO : file1.copy5.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/06 09:00:00 INFO : subdir/file20.txt: Copied (new)
2025/03/06 09:00:00 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/06 09:00:00 INFO : file1.txt: Copied (new)
2025/03/06 09:00:00 INFO : subdir: Set directory modification time (using SetModTime)
2025/03/06 09:00:00 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/03/06 09:00:00 DEBUG : Creating backend with remote "/tmp/085915zi/datadir/test_rmdirs-dututul1"
2025/03/06 09:00:00 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:00 DEBUG : Local file system at /tmp/085915zi/datadir/test_rmdirs-dututul1: Waiting for checks to finish
2025/03/06 09:00:00 DEBUG : Local file system at /tmp/085915zi/datadir/test_rmdirs-dututul1: Waiting for transfers to finish
2025/03/06 09:00:00 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:00 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/03/06 09:00:00 INFO : file1.txt: Copied (new)
2025/03/06 09:00:00 ERROR : : error listing: directory not found
2025/03/06 09:00:00 ERROR : : error listing: directory not found
2025/03/06 09:00:00 NOTICE: checking initFs Local file system at /tmp/085915zi/initdir/test_rmdirs-qizuxah8
2025/03/06 09:00:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:00:01 INFO : subdir: Making directory
2025/03/06 09:00:02 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:02 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path1': Waiting for checks to finish
2025/03/06 09:00:02 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path1': Waiting for transfers to finish
2025/03/06 09:00:06 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:06 INFO : file1.copy1.txt: Copied (new)
2025/03/06 09:00:06 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:06 INFO : file1.copy2.txt: Copied (new)
2025/03/06 09:00:09 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:09 INFO : file1.copy4.txt: Copied (new)
2025/03/06 09:00:10 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:10 INFO : file1.txt: Copied (new)
2025/03/06 09:00:10 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 09:00:11 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:11 INFO : file1.copy3.txt: Copied (new)
2025/03/06 09:00:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:00:13 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:00:13 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:13 INFO : subdir/file20.txt: Copied (new)
2025/03/06 09:00:14 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:14 INFO : file1.copy5.txt: Copied (new)
2025/03/06 09:00:14 NOTICE: checking Path1 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path1'
2025/03/06 09:00:15 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:00:15 INFO : subdir: Making directory
2025/03/06 09:00:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:16 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path2': Waiting for checks to finish
2025/03/06 09:00:16 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path2': Waiting for transfers to finish
2025/03/06 09:00:18 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:18 INFO : file1.copy2.txt: Copied (new)
2025/03/06 09:00:20 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:20 INFO : file1.copy3.txt: Copied (new)
2025/03/06 09:00:22 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:22 INFO : file1.copy4.txt: Copied (new)
2025/03/06 09:00:22 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:22 INFO : file1.txt: Copied (new)
2025/03/06 09:00:22 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:22 INFO : file1.copy5.txt: Copied (new)
2025/03/06 09:00:22 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 09:00:23 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:23 INFO : file1.copy1.txt: Copied (new)
2025/03/06 09:00:23 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:00:23 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:00:23 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:23 INFO : subdir/file20.txt: Copied (new)
2025/03/06 09:00:23 NOTICE: checking path2 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/rmdirs/path2'
2025/03/06 09:00:24 NOTICE: [36m(01) :[0m [34mtest rmdirs[0m
2025/03/06 09:00:24 NOTICE: [36m(02) :[0m [34mtest initial bisync[0m
2025/03/06 09:00:24 NOTICE: [36m(03) :[0m [34mbisync resync[0m
2025/03/06 09:00:29 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 09:00:29 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 09:00:29 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:29 INFO : Copying Path2 files to Path1
2025/03/06 09:00:29 INFO : - [34mPath2[0m [35mResync is copying files to[0m - [36mPath1[0m
2025/03/06 09:00:29 INFO : - [36mPath1[0m [35mResync is copying files to[0m - [36mPath2[0m
2025/03/06 09:00:30 INFO : Resync updating listings
2025/03/06 09:00:30 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:30 INFO : [32mBisync successful[0m
2025/03/06 09:00:30 NOTICE: [36m(04) :[0m [34mtest 1. delete path1 subdir file[0m
2025/03/06 09:00:30 NOTICE: [36m(05) :[0m [34mdelete-file TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/subdir/file20.txt[0m
2025/03/06 09:00:32 INFO : file20.txt: Deleted
2025/03/06 09:00:32 NOTICE: [36m(06) :[0m [34mtest 2. run bisync without remove-empty-dirs[0m
2025/03/06 09:00:32 NOTICE: [36m(07) :[0m [34mbisync[0m
2025/03/06 09:00:37 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 09:00:37 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 09:00:37 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:37 INFO : Building Path1 and Path2 listings
2025/03/06 09:00:37 INFO : Path1 checking for diffs
2025/03/06 09:00:37 INFO : - [36mPath1[0m [35m[31mFile was deleted[0m[0m - [36msubdir/file20.txt[0m
2025/03/06 09:00:37 INFO : Path1: 1 changes: [32m 0 new[0m, [33m 0 modified[0m, [31m 1 deleted[0m
2025/03/06 09:00:37 INFO : Path2 checking for diffs
2025/03/06 09:00:37 INFO : Applying changes
2025/03/06 09:00:37 INFO : - [34mPath2[0m [35m[31mQueue delete[0m[0m - [36mTestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/subdir/file20.txt[0m
2025/03/06 09:00:37 INFO : - [36mPath1[0m [35mDo queued copies to[0m - [36mPath2[0m
2025/03/06 09:00:38 INFO : subdir/file20.txt: Deleted
2025/03/06 09:00:38 INFO : Updating listings
2025/03/06 09:00:38 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:38 INFO : [32mBisync successful[0m
2025/03/06 09:00:38 NOTICE: [36m(08) :[0m [34mtest 3. confirm the subdir still exists on both paths[0m
2025/03/06 09:00:38 NOTICE: [36m(09) :[0m [34mlist-dirs TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/[0m
2025/03/06 09:00:40 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/03/06 09:00:40 NOTICE: [36m(10) :[0m [34mlist-dirs TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/[0m
2025/03/06 09:00:42 NOTICE: subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2
2025/03/06 09:00:42 NOTICE: [36m(11) :[0m [34mtest 4. run bisync with remove-empty-dirs[0m
2025/03/06 09:00:42 NOTICE: [36m(12) :[0m [34mbisync remove-empty-dirs[0m
2025/03/06 09:00:46 INFO : [2mSetting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.[0m
2025/03/06 09:00:46 INFO : Bisyncing with Comparison Settings:
{
"Modtime": true,
"Size": true,
"Checksum": false,
"HashType1": 0,
"HashType2": 0,
"NoSlowHash": false,
"SlowHashSyncOnly": false,
"SlowHashDetected": false,
"DownloadHash": false
}
2025/03/06 09:00:46 INFO : Synching Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" with Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:46 INFO : Building Path1 and Path2 listings
2025/03/06 09:00:47 INFO : Path1 checking for diffs
2025/03/06 09:00:47 INFO : Path2 checking for diffs
2025/03/06 09:00:47 INFO : No changes found
2025/03/06 09:00:47 INFO : Updating listings
2025/03/06 09:00:47 INFO : Validating listings for Path1 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/" vs Path2 "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/"
2025/03/06 09:00:47 INFO : Removing empty directories
2025/03/06 09:00:47 INFO : subdir: Removing directory
2025/03/06 09:00:48 INFO : subdir: Removing directory
2025/03/06 09:00:48 INFO : [32mBisync successful[0m
2025/03/06 09:00:48 NOTICE: [36m(13) :[0m [34mtest 5. confirm the subdir has been removed on both paths[0m
2025/03/06 09:00:48 NOTICE: [36m(14) :[0m [34mlist-dirs TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path1/[0m
2025/03/06 09:00:48 NOTICE: [36m(15) :[0m [34mlist-dirs TestOpenDrive:rclone-test-dohayuq1deva/085915zi/rmdirs/path2/[0m
bisync_test.go:560: [32mTEST rmdirs PASSED[0m
=== RUN TestBisyncRemoteRemote/volatile
2025/03/06 09:00:49 INFO : path1: Making directory
2025/03/06 09:00:49 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/volatile/path1"
2025/03/06 09:00:50 DEBUG : Starting OpenDrive session with ID: b1684786bbe59f38245fb8764119ac2e4da14a55cbcb64df10a467524802129b
2025/03/06 09:00:51 INFO : path2: Making directory
2025/03/06 09:00:51 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dohayuq1deva/085915zi/volatile/path2"
2025/03/06 09:00:51 DEBUG : Starting OpenDrive session with ID: e84eb2db12f43ea86b93793d21cbc857e15a2f312fc8cfa4b71067c3ea5236a2
2025/03/06 09:00:52 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/03/06 09:00:52 DEBUG : Creating backend with remote "/tmp/085915zi/initdir/test_volatile-dafabaf3"
2025/03/06 09:00:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : Local file system at /tmp/085915zi/initdir/test_volatile-dafabaf3: Waiting for checks to finish
2025/03/06 09:00:52 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : Local file system at /tmp/085915zi/initdir/test_volatile-dafabaf3: Waiting for transfers to finish
2025/03/06 09:00:52 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/06 09:00:52 INFO : file1.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/06 09:00:52 INFO : file2.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/03/06 09:00:52 INFO : file4.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/03/06 09:00:52 INFO : file3.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/03/06 09:00:52 INFO : file5.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/03/06 09:00:52 INFO : file6.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/03/06 09:00:52 INFO : file7.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:00:52 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/03/06 09:00:52 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/06 09:00:52 INFO : file8.txt: Copied (new)
2025/03/06 09:00:52 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:00:52 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/03/06 09:00:52 DEBUG : Creating backend with remote "/tmp/085915zi/datadir/test_volatile-nunazom6"
2025/03/06 09:00:52 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:52 DEBUG : Local file system at /tmp/085915zi/datadir/test_volatile-nunazom6: Waiting for checks to finish
2025/03/06 09:00:52 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:52 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/03/06 09:00:52 INFO : dummy.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : Local file system at /tmp/085915zi/datadir/test_volatile-nunazom6: Waiting for transfers to finish
2025/03/06 09:00:52 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:52 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/03/06 09:00:52 INFO : file1.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:52 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/03/06 09:00:52 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/03/06 09:00:52 INFO : file10.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/03/06 09:00:52 INFO : file2.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:52 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/03/06 09:00:52 INFO : file11.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/03/06 09:00:52 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/03/06 09:00:52 INFO : file5L.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:52 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/03/06 09:00:52 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/03/06 09:00:52 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/03/06 09:00:52 INFO : file6.txt: Copied (new)
2025/03/06 09:00:52 INFO : file5R.txt: Copied (new)
2025/03/06 09:00:52 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/03/06 09:00:52 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/03/06 09:00:52 INFO : file7.txt: Copied (new)
2025/03/06 09:00:53 ERROR : : error listing: directory not found
2025/03/06 09:00:53 ERROR : : error listing: directory not found
2025/03/06 09:00:53 NOTICE: checking initFs Local file system at /tmp/085915zi/initdir/test_volatile-dafabaf3
2025/03/06 09:00:53 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:00:53 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path1': Waiting for checks to finish
2025/03/06 09:00:53 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path1': Waiting for transfers to finish
2025/03/06 09:00:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:56 INFO : file1.txt: Copied (new)
2025/03/06 09:00:57 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:57 INFO : file3.txt: Copied (new)
2025/03/06 09:00:57 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:57 INFO : file2.txt: Copied (new)
2025/03/06 09:00:58 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:58 INFO : file4.txt: Copied (new)
2025/03/06 09:00:58 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:58 INFO : file6.txt: Copied (new)
2025/03/06 09:00:59 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 09:00:59 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:00:59 INFO : file7.txt: Copied (new)
2025/03/06 09:01:00 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:00 INFO : file8.txt: Copied (new)
2025/03/06 09:01:00 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:00 INFO : file5.txt: Copied (new)
2025/03/06 09:01:00 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:01:00 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:01:00 NOTICE: checking Path1 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path1'
2025/03/06 09:01:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/06 09:01:01 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path2': Waiting for checks to finish
2025/03/06 09:01:01 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path2': Waiting for transfers to finish
2025/03/06 09:01:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:03 INFO : file1.txt: Copied (new)
2025/03/06 09:01:03 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:03 INFO : file3.txt: Copied (new)
2025/03/06 09:01:03 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:03 INFO : file4.txt: Copied (new)
2025/03/06 09:01:04 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:04 INFO : file2.txt: Copied (new)
2025/03/06 09:01:05 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:05 INFO : file5.txt: Copied (new)
2025/03/06 09:01:05 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:05 INFO : file6.txt: Copied (new)
2025/03/06 09:01:06 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:06 INFO : file7.txt: Copied (new)
2025/03/06 09:01:06 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/06 09:01:06 INFO : file8.txt: Copied (new)
2025/03/06 09:01:07 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2025/03/06 09:01:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/06 09:01:08 INFO : RCLONE_TEST: Copied (new)
2025/03/06 09:01:08 NOTICE: checking path2 OpenDrive root 'rclone-test-dohayuq1deva/085915zi/volatile/path2'
2025/03/06 09:01:08 NOTICE: [36m(01) :[0m [34mtest volatile[0m
2025/03/06 09:01:08 NOTICE: [36m(02) :[0m [34mtest initial bisync[0m
2025/03/06 09:01:08 NOTICE: [36m(03) :[0m [34mbisync resync[0m
bisync_test.go:937: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/03/06 09:01:08 DEBUG : OpenDrive root 'rclone-test-dohayuq1deva': Purge remote
--- PASS: TestBisyncRemoteRemote (114.90s)
--- PASS: TestBisyncRemoteRemote/max_delete_path2_force (41.50s)
--- PASS: TestBisyncRemoteRemote/rmdirs (52.85s)
--- SKIP: TestBisyncRemoteRemote/volatile (18.68s)
PASS
2025/03/06 09:01:09 DEBUG : OpenDrive root 'rclone-test-pofutus3rubu': Purge remote
2025/03/06 09:01:10 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestBisyncLocalRemote$/^rclone_args$|^TestBisyncRemoteLocal$/^filtersfile_checks$|^TestBisyncRemoteRemote$/^(max_delete_path2_force|rmdirs|volatile)$'" - Finished OK in 3m30.62290958s (try 2/5)