"./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(max_delete_path2_force|normalization)$|^TestBisyncRemoteLocal$/^(max_delete_path1|resolve)$|^TestBisyncRemoteRemote$/^(backupdir|ignorelistingchecksum)$'" - Starting (try 5/5)
2026/02/26 03:11:34 DEBUG : Creating backend with remote "TestInternxt:rclone-test-taganuz3riza"
2026/02/26 03:11:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/26 03:11:34 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:11:34 DEBUG : Creating backend with remote "/tmp/rclone2658324158"
=== RUN   TestBisyncRemoteLocal
2026/02/26 03:11:34 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hubized2rizi"
2026/02/26 03:11:35 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:11:35 NOTICE: remote: TestInternxt:rclone-test-hubized2rizi
=== RUN   TestBisyncRemoteLocal/max_delete_path1
2026/02/26 03:11:35 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hubized2rizi/031135qa"
2026/02/26 03:11:35 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:11:36 INFO  : path1: Making directory
2026/02/26 03:11:36 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hubized2rizi/031135qa/max_delete_path1/path1"
2026/02/26 03:11:36 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/tmp/031135qa"
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/tmp/031135qa/max_delete_path1/path2"
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/initial"
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/tmp/031135qa/initdir/test_max_delete_path1-bafukug4"
2026/02/26 03:11:37 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : Local file system at /tmp/031135qa/initdir/test_max_delete_path1-bafukug4: Waiting for checks to finish
2026/02/26 03:11:37 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:11:37 INFO  : file2.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : Local file system at /tmp/031135qa/initdir/test_max_delete_path1-bafukug4: Waiting for transfers to finish
2026/02/26 03:11:37 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:11:37 INFO  : file1.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2026/02/26 03:11:37 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2026/02/26 03:11:37 INFO  : file5.txt: Copied (new)
2026/02/26 03:11:37 INFO  : file6.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2026/02/26 03:11:37 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2026/02/26 03:11:37 INFO  : file7.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/02/26 03:11:37 INFO  : file8.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/02/26 03:11:37 INFO  : file3.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/02/26 03:11:37 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:11:37 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/02/26 03:11:37 INFO  : file4.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK
2026/02/26 03:11:37 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:11:37 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2026/02/26 03:11:37 INFO  : file9.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/modfiles"
2026/02/26 03:11:37 DEBUG : Creating backend with remote "/tmp/031135qa/datadir/test_max_delete_path1-puwexuh1"
2026/02/26 03:11:37 DEBUG : hold.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:37 DEBUG : Local file system at /tmp/031135qa/datadir/test_max_delete_path1-puwexuh1: Waiting for checks to finish
2026/02/26 03:11:37 DEBUG : Local file system at /tmp/031135qa/datadir/test_max_delete_path1-puwexuh1: Waiting for transfers to finish
2026/02/26 03:11:37 DEBUG : hold.txt.83686dab.partial: size = 59 OK
2026/02/26 03:11:37 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2026/02/26 03:11:37 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2026/02/26 03:11:37 INFO  : hold.txt: Copied (new)
2026/02/26 03:11:37 DEBUG : Waiting for deletions to finish
2026/02/26 03:11:37 ERROR : error listing: directory not found
2026/02/26 03:11:37 ERROR : Internxt root 'rclone-test-hubized2rizi/031135qa/max_delete_path1/path1': Failed to list "": directory not found
2026/02/26 03:11:37 DEBUG : removing 1 level 0 directories
2026/02/26 03:11:37 INFO  : Internxt root 'rclone-test-hubized2rizi/031135qa/max_delete_path1/path1': Removing directory
2026/02/26 03:11:38 ERROR : Failed to rmdir: directory not found
2026/02/26 03:11:38 DEBUG : Waiting for deletions to finish
2026/02/26 03:11:38 ERROR : error listing: directory not found
2026/02/26 03:11:38 ERROR : Local file system at /tmp/031135qa/max_delete_path1/path2: Failed to list "": directory not found
2026/02/26 03:11:38 DEBUG : removing 1 level 0 directories
2026/02/26 03:11:38 INFO  : Local file system at /tmp/031135qa/max_delete_path1/path2: Removing directory
2026/02/26 03:11:38 ERROR : Failed to rmdir: stat /tmp/031135qa/max_delete_path1/path2: no such file or directory
2026/02/26 03:11:38 ERROR : error listing: directory not found
2026/02/26 03:11:38 ERROR : error listing: directory not found
2026/02/26 03:11:38 NOTICE: checking initFs Local file system at /tmp/031135qa/initdir/test_max_delete_path1-bafukug4
2026/02/26 03:11:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:11:38 DEBUG : Internxt root 'rclone-test-hubized2rizi/031135qa/max_delete_path1/path1': Waiting for checks to finish
2026/02/26 03:11:38 DEBUG : Internxt root 'rclone-test-hubized2rizi/031135qa/max_delete_path1/path1': Waiting for transfers to finish
2026/02/26 03:11:39 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404)
2026/02/26 03:11:39 DEBUG : file3.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file3.txt: Copied (new)
2026/02/26 03:11:39 DEBUG : file2.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file2.txt: Copied (new)
2026/02/26 03:11:39 DEBUG : file4.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file4.txt: Copied (new)
2026/02/26 03:11:39 DEBUG : file6.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file6.txt: Copied (new)
2026/02/26 03:11:39 DEBUG : file8.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file8.txt: Copied (new)
2026/02/26 03:11:39 DEBUG : file7.txt: size = 0 OK
2026/02/26 03:11:39 INFO  : file7.txt: Copied (new)
2026/02/26 03:11:40 DEBUG : file5.txt: size = 0 OK
2026/02/26 03:11:40 INFO  : file5.txt: Copied (new)
2026/02/26 03:11:40 DEBUG : file9.txt: size = 109 OK
2026/02/26 03:11:40 INFO  : file9.txt: Copied (new)
    bisync_test.go:2013: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:2013
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:506
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	failed to create empty file metadata: create meta: Folder not found (status 404)
        	Test:       	TestBisyncRemoteLocal/max_delete_path1
        	Messages:   	setting up path1
2026/02/26 03:11:40 NOTICE: checking Path1 Internxt root 'rclone-test-hubized2rizi/031135qa/max_delete_path1/path1'
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5
    fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5
    fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Should be true
        	Test:       	TestBisyncRemoteLocal/max_delete_path1
        	Messages:   	listing wrong, want
        	            	  file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109) got
        	            	  file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109)
    fstest.go:203: Not found "file1.txt"
    fstest.go:206: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncRemoteLocal/max_delete_path1
        	Messages:   	1 objects not found
2026/02/26 03:12:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:12 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:12:12 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : Local file system at /tmp/031135qa/max_delete_path1/path2: Waiting for checks to finish
2026/02/26 03:12:12 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : Local file system at /tmp/031135qa/max_delete_path1/path2: Waiting for transfers to finish
2026/02/26 03:12:12 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/02/26 03:12:12 INFO  : file3.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:12:12 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/02/26 03:12:12 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:12 INFO  : file4.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2026/02/26 03:12:12 INFO  : file7.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2026/02/26 03:12:12 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2026/02/26 03:12:12 INFO  : file8.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2026/02/26 03:12:12 INFO  : file6.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:12 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2026/02/26 03:12:12 INFO  : file5.txt: Copied (new)
2026/02/26 03:12:12 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK
2026/02/26 03:12:12 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:12:12 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2026/02/26 03:12:12 INFO  : file9.txt: Copied (new)
2026/02/26 03:12:12 NOTICE: checking path2 Local file system at /tmp/031135qa/max_delete_path1/path2
2026/02/26 03:12:12 NOTICE: (01)  : test max-delete-path1
2026/02/26 03:12:12 NOTICE: (02)  : test initial bisync
2026/02/26 03:12:12 NOTICE: (03)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
=== RUN   TestBisyncRemoteLocal/resolve
2026/02/26 03:12:12 INFO  : path1: Making directory
2026/02/26 03:12:12 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hubized2rizi/031135qa/resolve/path1"
2026/02/26 03:12:13 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:12:13 DEBUG : Creating backend with remote "/tmp/031135qa/resolve/path2"
2026/02/26 03:12:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial"
2026/02/26 03:12:13 DEBUG : Creating backend with remote "/tmp/031135qa/initdir/test_resolve-soluden7"
2026/02/26 03:12:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : Local file system at /tmp/031135qa/initdir/test_resolve-soluden7: Waiting for checks to finish
2026/02/26 03:12:13 DEBUG : Local file system at /tmp/031135qa/initdir/test_resolve-soluden7: Waiting for transfers to finish
2026/02/26 03:12:13 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:12:13 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:12:13 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:13 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:12:13 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:12:13 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:13 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:13 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:12:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:12:13 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:13 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:12:13 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:12:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles"
2026/02/26 03:12:13 DEBUG : Creating backend with remote "/tmp/031135qa/datadir/test_resolve-cedijaj1"
2026/02/26 03:12:13 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:13 DEBUG : Local file system at /tmp/031135qa/datadir/test_resolve-cedijaj1: Waiting for checks to finish
2026/02/26 03:12:13 DEBUG : Local file system at /tmp/031135qa/datadir/test_resolve-cedijaj1: Waiting for transfers to finish
2026/02/26 03:12:13 DEBUG : file1L.txt.6455455d.partial: size = 33 OK
2026/02/26 03:12:13 DEBUG : file2.txt.67fcf9d0.partial: size = 37 OK
2026/02/26 03:12:13 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2026/02/26 03:12:13 DEBUG : file1L.txt.6455455d.partial: renamed to: file1L.txt
2026/02/26 03:12:13 INFO  : file1L.txt: Copied (new)
2026/02/26 03:12:13 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2026/02/26 03:12:13 DEBUG : file2.txt.67fcf9d0.partial: renamed to: file2.txt
2026/02/26 03:12:13 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:13 DEBUG : file1R.txt.f5ef699a.partial: size = 33 OK
2026/02/26 03:12:13 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK
2026/02/26 03:12:13 DEBUG : file1R.txt.f5ef699a.partial: renamed to: file1R.txt
2026/02/26 03:12:13 INFO  : file1R.txt: Copied (new)
2026/02/26 03:12:13 DEBUG : Waiting for deletions to finish
2026/02/26 03:12:14 ERROR : error listing: directory not found
2026/02/26 03:12:14 ERROR : Internxt root 'rclone-test-hubized2rizi/031135qa/resolve/path1': Failed to list "": directory not found
2026/02/26 03:12:14 DEBUG : removing 1 level 0 directories
2026/02/26 03:12:14 INFO  : Internxt root 'rclone-test-hubized2rizi/031135qa/resolve/path1': Removing directory
2026/02/26 03:12:14 ERROR : Failed to rmdir: directory not found
2026/02/26 03:12:14 DEBUG : Waiting for deletions to finish
2026/02/26 03:12:14 ERROR : error listing: directory not found
2026/02/26 03:12:14 ERROR : Local file system at /tmp/031135qa/resolve/path2: Failed to list "": directory not found
2026/02/26 03:12:14 DEBUG : removing 1 level 0 directories
2026/02/26 03:12:14 INFO  : Local file system at /tmp/031135qa/resolve/path2: Removing directory
2026/02/26 03:12:14 ERROR : Failed to rmdir: stat /tmp/031135qa/resolve/path2: no such file or directory
2026/02/26 03:12:14 ERROR : error listing: directory not found
2026/02/26 03:12:14 ERROR : error listing: directory not found
2026/02/26 03:12:14 NOTICE: checking initFs Local file system at /tmp/031135qa/initdir/test_resolve-soluden7
2026/02/26 03:12:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:12:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:14 DEBUG : Internxt root 'rclone-test-hubized2rizi/031135qa/resolve/path1': Waiting for checks to finish
2026/02/26 03:12:14 DEBUG : Internxt root 'rclone-test-hubized2rizi/031135qa/resolve/path1': Waiting for transfers to finish
2026/02/26 03:12:15 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:12:15 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:15 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/26 03:12:15 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:12:15 DEBUG : file2.txt: size = 0 OK
2026/02/26 03:12:15 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:15 NOTICE: checking Path1 Internxt root 'rclone-test-hubized2rizi/031135qa/resolve/path1'
2026/02/26 03:12:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:12:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:15 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:15 DEBUG : Local file system at /tmp/031135qa/resolve/path2: Waiting for checks to finish
2026/02/26 03:12:15 DEBUG : Local file system at /tmp/031135qa/resolve/path2: Waiting for transfers to finish
2026/02/26 03:12:15 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:12:15 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:12:15 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:15 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:12:15 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:12:15 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:15 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:12:15 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:12:15 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:12:15 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:15 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:12:15 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:15 NOTICE: checking path2 Local file system at /tmp/031135qa/resolve/path2
2026/02/26 03:12:15 NOTICE: (01)  : test resolve
2026/02/26 03:12:15 NOTICE: (02)  : test initial bisync
2026/02/26 03:12:15 NOTICE: (03)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
2026/02/26 03:12:17 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/resolve/path1/RCLONE_TEST"
2026/02/26 03:12:17 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/resolve/path1/file1.txt"
2026/02/26 03:12:17 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/resolve/path1/file2.txt"
2026/02/26 03:12:18 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file2.txt"
2026/02/26 03:12:18 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file3.txt"
2026/02/26 03:12:18 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file4.txt"
2026/02/26 03:12:18 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file5.txt"
2026/02/26 03:12:19 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file6.txt"
2026/02/26 03:12:19 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file7.txt"
2026/02/26 03:12:19 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file8.txt"
2026/02/26 03:12:19 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge object "031135qa/max_delete_path1/path1/file9.txt"
2026/02/26 03:12:20 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa/resolve/path1"
2026/02/26 03:12:20 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa/resolve"
2026/02/26 03:12:20 NOTICE: purge failed to rmdir "031135qa/resolve": directory not empty
2026/02/26 03:12:20 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa/path1"
2026/02/26 03:12:20 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa/max_delete_path1/path1"
2026/02/26 03:12:21 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa/max_delete_path1"
2026/02/26 03:12:21 NOTICE: purge failed to rmdir "031135qa/max_delete_path1": directory not empty
2026/02/26 03:12:21 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir "031135qa"
2026/02/26 03:12:21 NOTICE: purge failed to rmdir "031135qa": directory not empty
2026/02/26 03:12:21 DEBUG : Internxt root 'rclone-test-hubized2rizi': Purge dir ""
2026/02/26 03:12:21 NOTICE: purge failed to rmdir "": directory not empty
--- FAIL: TestBisyncRemoteLocal (46.40s)
    --- FAIL: TestBisyncRemoteLocal/max_delete_path1 (37.47s)
    --- SKIP: TestBisyncRemoteLocal/resolve (3.00s)
=== RUN   TestBisyncLocalRemote
2026/02/26 03:12:21 DEBUG : Creating backend with remote "TestInternxt:rclone-test-kunerec2dasu"
2026/02/26 03:12:21 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:12:21 NOTICE: remote: TestInternxt:rclone-test-kunerec2dasu
=== RUN   TestBisyncLocalRemote/max_delete_path2_force
2026/02/26 03:12:21 DEBUG : Creating backend with remote "/tmp/031221co"
2026/02/26 03:12:21 DEBUG : Creating backend with remote "/tmp/031221co/max_delete_path2_force/path1"
2026/02/26 03:12:21 DEBUG : Creating backend with remote "TestInternxt:rclone-test-kunerec2dasu/031221co"
2026/02/26 03:12:22 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:12:22 INFO  : path2: Making directory
2026/02/26 03:12:23 DEBUG : Creating backend with remote "TestInternxt:rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2"
2026/02/26 03:12:23 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:12:24 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/initial"
2026/02/26 03:12:24 DEBUG : Creating backend with remote "/tmp/031221co/initdir/test_max_delete_path2_force-xujarit9"
2026/02/26 03:12:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/initdir/test_max_delete_path2_force-xujarit9: Waiting for checks to finish
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/initdir/test_max_delete_path2_force-xujarit9: Waiting for transfers to finish
2026/02/26 03:12:24 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/02/26 03:12:24 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:12:24 INFO  : file4.txt: Copied (new)
2026/02/26 03:12:24 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2026/02/26 03:12:24 INFO  : file6.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/02/26 03:12:24 INFO  : file3.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:12:24 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2026/02/26 03:12:24 INFO  : file8.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2026/02/26 03:12:24 INFO  : file5.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2026/02/26 03:12:24 INFO  : file7.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK
2026/02/26 03:12:24 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:12:24 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2026/02/26 03:12:24 INFO  : file9.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path2_force/modfiles"
2026/02/26 03:12:24 DEBUG : Creating backend with remote "/tmp/031221co/datadir/test_max_delete_path2_force-titapak7"
2026/02/26 03:12:24 DEBUG : hold.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/datadir/test_max_delete_path2_force-titapak7: Waiting for checks to finish
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/datadir/test_max_delete_path2_force-titapak7: Waiting for transfers to finish
2026/02/26 03:12:24 DEBUG : hold.txt.83686dab.partial: size = 59 OK
2026/02/26 03:12:24 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2026/02/26 03:12:24 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2026/02/26 03:12:24 INFO  : hold.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : Waiting for deletions to finish
2026/02/26 03:12:24 ERROR : error listing: directory not found
2026/02/26 03:12:24 ERROR : Local file system at /tmp/031221co/max_delete_path2_force/path1: Failed to list "": directory not found
2026/02/26 03:12:24 DEBUG : removing 1 level 0 directories
2026/02/26 03:12:24 INFO  : Local file system at /tmp/031221co/max_delete_path2_force/path1: Removing directory
2026/02/26 03:12:24 ERROR : Failed to rmdir: stat /tmp/031221co/max_delete_path2_force/path1: no such file or directory
2026/02/26 03:12:24 DEBUG : Waiting for deletions to finish
2026/02/26 03:12:24 ERROR : error listing: directory not found
2026/02/26 03:12:24 ERROR : Internxt root 'rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2': Failed to list "": directory not found
2026/02/26 03:12:24 DEBUG : removing 1 level 0 directories
2026/02/26 03:12:24 INFO  : Internxt root 'rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2': Removing directory
2026/02/26 03:12:24 ERROR : Failed to rmdir: directory not found
2026/02/26 03:12:24 ERROR : error listing: directory not found
2026/02/26 03:12:24 ERROR : error listing: directory not found
2026/02/26 03:12:24 NOTICE: checking initFs Local file system at /tmp/031221co/initdir/test_max_delete_path2_force-xujarit9
2026/02/26 03:12:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/max_delete_path2_force/path1: Waiting for checks to finish
2026/02/26 03:12:24 DEBUG : Local file system at /tmp/031221co/max_delete_path2_force/path1: Waiting for transfers to finish
2026/02/26 03:12:24 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:12:24 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:12:24 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 INFO  : file2.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/02/26 03:12:24 INFO  : file3.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/02/26 03:12:24 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2026/02/26 03:12:24 INFO  : file4.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2026/02/26 03:12:24 INFO  : file5.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2026/02/26 03:12:24 INFO  : file6.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2026/02/26 03:12:24 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:12:24 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2026/02/26 03:12:24 INFO  : file8.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK
2026/02/26 03:12:24 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2026/02/26 03:12:24 INFO  : file7.txt: Copied (new)
2026/02/26 03:12:24 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:12:24 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2026/02/26 03:12:24 INFO  : file9.txt: Copied (new)
2026/02/26 03:12:24 NOTICE: checking Path1 Local file system at /tmp/031221co/max_delete_path2_force/path1
2026/02/26 03:12:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : file9.txt: Need to transfer - File not found at Destination
2026/02/26 03:12:25 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2': Waiting for checks to finish
2026/02/26 03:12:25 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2': Waiting for transfers to finish
2026/02/26 03:12:25 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404)
2026/02/26 03:12:25 DEBUG : file4.txt: size = 0 OK
2026/02/26 03:12:25 INFO  : file4.txt: Copied (new)
2026/02/26 03:12:25 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:12:25 INFO  : file1.txt: Copied (new)
2026/02/26 03:12:25 DEBUG : file3.txt: size = 0 OK
2026/02/26 03:12:25 INFO  : file3.txt: Copied (new)
2026/02/26 03:12:26 DEBUG : file8.txt: size = 0 OK
2026/02/26 03:12:26 INFO  : file8.txt: Copied (new)
2026/02/26 03:12:26 DEBUG : file5.txt: size = 0 OK
2026/02/26 03:12:26 INFO  : file5.txt: Copied (new)
2026/02/26 03:12:26 DEBUG : file7.txt: size = 0 OK
2026/02/26 03:12:26 INFO  : file7.txt: Copied (new)
2026/02/26 03:12:26 DEBUG : file6.txt: size = 0 OK
2026/02/26 03:12:26 INFO  : file6.txt: Copied (new)
2026/02/26 03:12:27 DEBUG : file9.txt: size = 109 OK
2026/02/26 03:12:27 INFO  : file9.txt: Copied (new)
    bisync_test.go:2013: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:2013
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:509
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	failed to create empty file metadata: create meta: Folder not found (status 404)
        	Test:       	TestBisyncLocalRemote/max_delete_path2_force
        	Messages:   	setting up path2
2026/02/26 03:12:27 NOTICE: checking path2 Internxt root 'rclone-test-kunerec2dasu/031221co/max_delete_path2_force/path2'
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5
    fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5
    fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:511
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Should be true
        	Test:       	TestBisyncLocalRemote/max_delete_path2_force
        	Messages:   	listing wrong, want
        	            	  file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109) got
        	            	  file1.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0), file9.txt (109)
    fstest.go:203: Not found "file2.txt"
    fstest.go:206: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:511
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncLocalRemote/max_delete_path2_force
        	Messages:   	1 objects not found
2026/02/26 03:12:59 NOTICE: (01)  : test max-delete-path2-force
2026/02/26 03:12:59 NOTICE: (02)  : test initial bisync
2026/02/26 03:12:59 NOTICE: (03)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
=== RUN   TestBisyncLocalRemote/normalization
2026/02/26 03:12:59 DEBUG : Creating backend with remote "/tmp/031221co/normalization/path1"
2026/02/26 03:12:59 INFO  : path2: Making directory
2026/02/26 03:12:59 DEBUG : Creating backend with remote "TestInternxt:rclone-test-kunerec2dasu/031221co/normalization/path2"
2026/02/26 03:12:59 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:01 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/initial"
2026/02/26 03:13:01 DEBUG : Creating backend with remote "/tmp/031221co/initdir/test_normalization-modicof1"
2026/02/26 03:13:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2026/02/26 03:13:01 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:13:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:13:01 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:13:01 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:01 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/initdir/test_normalization-modicof1: Waiting for checks to finish
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/initdir/test_normalization-modicof1: Waiting for transfers to finish
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: size = 272 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: size = 0 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: size = 272 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:01 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:13:01 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2026/02/26 03:13:01 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/modfiles"
2026/02/26 03:13:01 DEBUG : Creating backend with remote "/tmp/031221co/datadir/test_normalization-fufofom0"
2026/02/26 03:13:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/datadir/test_normalization-fufofom0: Waiting for checks to finish
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/datadir/test_normalization-fufofom0: Waiting for transfers to finish
2026/02/26 03:13:01 DEBUG : 測試_filtersfile.txt.3ada41b4.partial: size = 254 OK
2026/02/26 03:13:01 DEBUG : file1.txt.75b77f44.partial: size = 19 OK
2026/02/26 03:13:01 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2026/02/26 03:13:01 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:01 DEBUG : 測試_filtersfile.txt.3ada41b4.partial: renamed to: 測試_filtersfile.txt
2026/02/26 03:13:01 INFO  : 測試_filtersfile.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt
2026/02/26 03:13:01 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:01 ERROR : error listing: directory not found
2026/02/26 03:13:01 ERROR : Local file system at /tmp/031221co/normalization/path1: Failed to list "": directory not found
2026/02/26 03:13:01 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:01 INFO  : Local file system at /tmp/031221co/normalization/path1: Removing directory
2026/02/26 03:13:01 ERROR : Failed to rmdir: stat /tmp/031221co/normalization/path1: no such file or directory
2026/02/26 03:13:01 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:01 ERROR : error listing: directory not found
2026/02/26 03:13:01 ERROR : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Failed to list "": directory not found
2026/02/26 03:13:01 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:01 INFO  : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Removing directory
2026/02/26 03:13:01 ERROR : Failed to rmdir: directory not found
2026/02/26 03:13:01 ERROR : error listing: directory not found
2026/02/26 03:13:01 ERROR : error listing: directory not found
2026/02/26 03:13:01 NOTICE: checking initFs Local file system at /tmp/031221co/initdir/test_normalization-modicof1
2026/02/26 03:13:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2026/02/26 03:13:01 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/normalization/path1: Waiting for checks to finish
2026/02/26 03:13:01 DEBUG : Local file system at /tmp/031221co/normalization/path1: Waiting for transfers to finish
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: size = 272 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.b8f49a2a.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: size = 0 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.062d4dd7.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: size = 272 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2026/02/26 03:13:01 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.24b96bc5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2026/02/26 03:13:01 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:13:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:13:01 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:13:01 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:01 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:13:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:01 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:13:01 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2026/02/26 03:13:01 NOTICE: checking Path1 Local file system at /tmp/031221co/normalization/path1
2026/02/26 03:13:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:01 INFO  : 測試_Русский_ _ _ě_áñ: Making directory
2026/02/26 03:13:02 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:02 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:02 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2026/02/26 03:13:02 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Waiting for checks to finish
2026/02/26 03:13:02 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Waiting for transfers to finish
2026/02/26 03:13:03 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/26 03:13:03 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: size = 272 OK
2026/02/26 03:13:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2026/02/26 03:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: size = 0 OK
2026/02/26 03:13:03 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2026/02/26 03:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: size = 272 OK
2026/02/26 03:13:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2026/02/26 03:13:04 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:13:04 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:04 NOTICE: checking path2 Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2'
2026/02/26 03:13:04 NOTICE: (01)  : test normalization
2026/02/26 03:13:04 NOTICE: (02)  : touch-copy 2001-01-02 /tmp/031221co/datadir/test_normalization-fufofom0/file1.txt TestInternxt:rclone-test-kunerec2dasu/031221co/normalization/path2/
2026/02/26 03:13:04 DEBUG : Creating backend with remote "/tmp/031221co/datadir/test_normalization-fufofom0/"
2026/02/26 03:13:04 DEBUG : fs cache: renaming cache item "/tmp/031221co/datadir/test_normalization-fufofom0/" to be canonical "/tmp/031221co/datadir/test_normalization-fufofom0"
2026/02/26 03:13:04 DEBUG : file1.txt: Set modification time 2001-01-02
2026/02/26 03:13:04 DEBUG : copyFile "/tmp/031221co/datadir/test_normalization-fufofom0/file1.txt" to "TestInternxt:rclone-test-kunerec2dasu/031221co/normalization/path2/" as ""
2026/02/26 03:13:04 DEBUG : Creating backend with remote "/tmp/031221co/datadir/test_normalization-fufofom0/file1.txt"
2026/02/26 03:13:04 DEBUG : Creating backend with remote "TestInternxt:rclone-test-kunerec2dasu/031221co/normalization/path2/"
2026/02/26 03:13:04 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:05 DEBUG : operations.CopyFile "file1.txt" to "Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2'" as "file1.txt"
2026/02/26 03:13:05 DEBUG : file1.txt: size = 19 (Local file system at /tmp/031221co/datadir/test_normalization-fufofom0)
2026/02/26 03:13:05 DEBUG : file1.txt: size = 0 (Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2')
2026/02/26 03:13:05 DEBUG : file1.txt: Sizes differ
2026/02/26 03:13:05 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Renamed existing file file1.txt to backup file1.rclone-backup-lavibeg4.txt (UUID: 48f20e62-4c14-4ff2-847d-b50361922d9e)
2026/02/26 03:13:06 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Upload succeeded, deleting backup file file1.rclone-backup-lavibeg4.txt (UUID: 48f20e62-4c14-4ff2-847d-b50361922d9e)
2026/02/26 03:13:06 DEBUG : Internxt root 'rclone-test-kunerec2dasu/031221co/normalization/path2': Successfully deleted backup file
2026/02/26 03:13:06 DEBUG : file1.txt: size = 19 OK
2026/02/26 03:13:06 INFO  : file1.txt: Copied (replaced existing)
2026/02/26 03:13:06 NOTICE: (03)  : test initial bisync
2026/02/26 03:13:06 NOTICE: (04)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
2026/02/26 03:13:08 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file1.txt"
2026/02/26 03:13:08 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file3.txt"
2026/02/26 03:13:08 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file4.txt"
2026/02/26 03:13:08 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file5.txt"
2026/02/26 03:13:08 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file6.txt"
2026/02/26 03:13:09 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file7.txt"
2026/02/26 03:13:09 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file8.txt"
2026/02/26 03:13:09 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/max_delete_path2_force/path2/file9.txt"
2026/02/26 03:13:09 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/normalization/path2/RCLONE_TEST"
2026/02/26 03:13:09 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/normalization/path2/file1.txt"
2026/02/26 03:13:10 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/normalization/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt"
2026/02/26 03:13:10 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/normalization/path2/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt"
2026/02/26 03:13:10 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge object "031221co/normalization/path2/測試_Русский_ _ _ě_áñ/測試_check file"
2026/02/26 03:13:10 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/path2"
2026/02/26 03:13:11 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/normalization/path2/測試_Русский_ _ _ě_áñ"
2026/02/26 03:13:11 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/normalization/path2"
2026/02/26 03:13:11 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/normalization"
2026/02/26 03:13:12 NOTICE: purge failed to rmdir "031221co/normalization": directory not empty
2026/02/26 03:13:12 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/max_delete_path2_force/path2"
2026/02/26 03:13:12 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co/max_delete_path2_force"
2026/02/26 03:13:12 NOTICE: purge failed to rmdir "031221co/max_delete_path2_force": directory not empty
2026/02/26 03:13:12 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir "031221co"
2026/02/26 03:13:12 NOTICE: purge failed to rmdir "031221co": directory not empty
2026/02/26 03:13:12 DEBUG : Internxt root 'rclone-test-kunerec2dasu': Purge dir ""
2026/02/26 03:13:12 NOTICE: purge failed to rmdir "": directory not empty
--- FAIL: TestBisyncLocalRemote (51.33s)
    --- FAIL: TestBisyncLocalRemote/max_delete_path2_force (37.72s)
    --- SKIP: TestBisyncLocalRemote/normalization (6.88s)
=== RUN   TestBisyncRemoteRemote
2026/02/26 03:13:12 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi"
2026/02/26 03:13:12 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:13 NOTICE: remote: TestInternxt:rclone-test-hapemuc1yubi
=== RUN   TestBisyncRemoteRemote/backupdir
2026/02/26 03:13:13 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi/031313xi"
2026/02/26 03:13:13 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:13 INFO  : path1: Making directory
2026/02/26 03:13:14 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi/031313xi/backupdir/path1"
2026/02/26 03:13:14 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:15 INFO  : path2: Making directory
2026/02/26 03:13:15 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi/031313xi/backupdir/path2"
2026/02/26 03:13:15 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/initial"
2026/02/26 03:13:17 DEBUG : Creating backend with remote "/tmp/031313xi/initdir/test_backupdir-vitavod1"
2026/02/26 03:13:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:13:17 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:13:17 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/02/26 03:13:17 INFO  : file3.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:13:17 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:17 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2026/02/26 03:13:17 INFO  : file5.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/26 03:13:17 INFO  : file2.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : Local file system at /tmp/031313xi/initdir/test_backupdir-vitavod1: Waiting for checks to finish
2026/02/26 03:13:17 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/02/26 03:13:17 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2026/02/26 03:13:17 INFO  : file7.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : Local file system at /tmp/031313xi/initdir/test_backupdir-vitavod1: Waiting for transfers to finish
2026/02/26 03:13:17 INFO  : file4.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:13:17 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2026/02/26 03:13:17 INFO  : file6.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2026/02/26 03:13:17 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:17 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2026/02/26 03:13:17 INFO  : file8.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/modfiles"
2026/02/26 03:13:17 DEBUG : Creating backend with remote "/tmp/031313xi/datadir/test_backupdir-yecomap7"
2026/02/26 03:13:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file10.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file11.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:17 DEBUG : Local file system at /tmp/031313xi/datadir/test_backupdir-yecomap7: Waiting for checks to finish
2026/02/26 03:13:17 DEBUG : Local file system at /tmp/031313xi/datadir/test_backupdir-yecomap7: Waiting for transfers to finish
2026/02/26 03:13:17 DEBUG : file2.txt.4526d70e.partial: size = 13 OK
2026/02/26 03:13:17 DEBUG : file10.txt.c02780cb.partial: size = 19 OK
2026/02/26 03:13:17 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2026/02/26 03:13:17 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:17 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt
2026/02/26 03:13:17 INFO  : file2.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt
2026/02/26 03:13:17 INFO  : file10.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file11.txt.40d797d4.partial: size = 19 OK
2026/02/26 03:13:17 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:17 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt
2026/02/26 03:13:17 INFO  : file11.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file1.txt.75b77f44.partial: size = 19 OK
2026/02/26 03:13:17 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:17 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt
2026/02/26 03:13:17 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK
2026/02/26 03:13:17 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2026/02/26 03:13:17 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK
2026/02/26 03:13:17 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2026/02/26 03:13:17 DEBUG : file6.txt.4185169b.partial: size = 19 OK
2026/02/26 03:13:17 DEBUG : file7.txt.c1750184.partial: size = 19 OK
2026/02/26 03:13:17 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2026/02/26 03:13:17 INFO  : file5R.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:17 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:17 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2026/02/26 03:13:17 INFO  : file5L.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt
2026/02/26 03:13:17 INFO  : file6.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt
2026/02/26 03:13:17 INFO  : file7.txt: Copied (new)
2026/02/26 03:13:17 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:17 ERROR : error listing: directory not found
2026/02/26 03:13:17 ERROR : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path1': Failed to list "": directory not found
2026/02/26 03:13:17 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:17 INFO  : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path1': Removing directory
2026/02/26 03:13:17 ERROR : Failed to rmdir: directory not found
2026/02/26 03:13:17 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:17 ERROR : error listing: directory not found
2026/02/26 03:13:18 ERROR : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path2': Failed to list "": directory not found
2026/02/26 03:13:18 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:18 INFO  : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path2': Removing directory
2026/02/26 03:13:18 ERROR : Failed to rmdir: directory not found
2026/02/26 03:13:18 ERROR : error listing: directory not found
2026/02/26 03:13:18 ERROR : error listing: directory not found
2026/02/26 03:13:18 NOTICE: checking initFs Local file system at /tmp/031313xi/initdir/test_backupdir-vitavod1
2026/02/26 03:13:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:18 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path1': Waiting for checks to finish
2026/02/26 03:13:18 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path1': Waiting for transfers to finish
2026/02/26 03:13:19 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/26 03:13:19 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:19 DEBUG : file3.txt: size = 0 OK
2026/02/26 03:13:19 INFO  : file3.txt: Copied (new)
2026/02/26 03:13:20 DEBUG : file2.txt: size = 0 OK
2026/02/26 03:13:20 INFO  : file2.txt: Copied (new)
2026/02/26 03:13:20 DEBUG : file5.txt: size = 0 OK
2026/02/26 03:13:20 INFO  : file5.txt: Copied (new)
2026/02/26 03:13:20 DEBUG : file4.txt: size = 0 OK
2026/02/26 03:13:20 INFO  : file4.txt: Copied (new)
2026/02/26 03:13:20 DEBUG : file6.txt: size = 0 OK
2026/02/26 03:13:20 INFO  : file6.txt: Copied (new)
2026/02/26 03:13:20 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:13:20 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:21 DEBUG : file8.txt: size = 0 OK
2026/02/26 03:13:21 INFO  : file8.txt: Copied (new)
2026/02/26 03:13:21 DEBUG : file7.txt: size = 0 OK
2026/02/26 03:13:21 INFO  : file7.txt: Copied (new)
2026/02/26 03:13:21 NOTICE: checking Path1 Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path1'
2026/02/26 03:13:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : file8.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:21 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path2': Waiting for checks to finish
2026/02/26 03:13:21 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path2': Waiting for transfers to finish
2026/02/26 03:13:22 DEBUG : file2.txt: size = 0 OK
2026/02/26 03:13:22 INFO  : file2.txt: Copied (new)
2026/02/26 03:13:22 DEBUG : file4.txt: size = 0 OK
2026/02/26 03:13:22 INFO  : file4.txt: Copied (new)
2026/02/26 03:13:22 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/26 03:13:22 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:23 DEBUG : file5.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file5.txt: Copied (new)
2026/02/26 03:13:23 DEBUG : file3.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file3.txt: Copied (new)
2026/02/26 03:13:23 DEBUG : file6.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file6.txt: Copied (new)
2026/02/26 03:13:23 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:23 DEBUG : file8.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file8.txt: Copied (new)
2026/02/26 03:13:23 DEBUG : file7.txt: size = 0 OK
2026/02/26 03:13:23 INFO  : file7.txt: Copied (new)
2026/02/26 03:13:23 NOTICE: checking path2 Internxt root 'rclone-test-hapemuc1yubi/031313xi/backupdir/path2'
2026/02/26 03:13:24 NOTICE: (01)  : test backupdir
2026/02/26 03:13:24 NOTICE: (02)  : test initial bisync
2026/02/26 03:13:24 NOTICE: (03)  : bisync resync backupdir1=/tmp/031313xi/workdir/backupdirs/backupdir1 backupdir2=/tmp/031313xi/workdir/backupdirs/backupdir2
    bisync_test.go:975: backupdir test currently only works on local (it uses the workdir)
=== RUN   TestBisyncRemoteRemote/ignorelistingchecksum
2026/02/26 03:13:24 INFO  : path1: Making directory
2026/02/26 03:13:24 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1"
2026/02/26 03:13:24 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:25 INFO  : path2: Making directory
2026/02/26 03:13:25 DEBUG : Creating backend with remote "TestInternxt:rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2"
2026/02/26 03:13:25 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/26 03:13:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ignorelistingchecksum/initial"
2026/02/26 03:13:26 DEBUG : Creating backend with remote "/tmp/031313xi/initdir/test_ignorelistingchecksum-doraqas0"
2026/02/26 03:13:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : subdir: Making directory with metadata
2026/02/26 03:13:26 INFO  : subdir: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2026/02/26 03:13:26 DEBUG : Added delayed dir = "subdir", newDst=subdir
2026/02/26 03:13:26 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2026/02/26 03:13:26 INFO  : file1.copy1.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2026/02/26 03:13:26 INFO  : file1.copy3.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/26 03:13:26 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2026/02/26 03:13:26 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 INFO  : file1.copy2.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2026/02/26 03:13:26 INFO  : file1.copy4.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/26 03:13:26 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : Local file system at /tmp/031313xi/initdir/test_ignorelistingchecksum-doraqas0: Waiting for checks to finish
2026/02/26 03:13:26 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : Local file system at /tmp/031313xi/initdir/test_ignorelistingchecksum-doraqas0: Waiting for transfers to finish
2026/02/26 03:13:26 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2026/02/26 03:13:26 INFO  : subdir/file20.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/26 03:13:26 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/26 03:13:26 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:13:26 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2026/02/26 03:13:26 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/26 03:13:26 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2026/02/26 03:13:26 INFO  : file1.copy5.txt: Copied (new)
2026/02/26 03:13:26 INFO  : subdir: Set directory modification time (using SetModTime)
2026/02/26 03:13:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ignorelistingchecksum/modfiles"
2026/02/26 03:13:26 DEBUG : Creating backend with remote "/tmp/031313xi/datadir/test_ignorelistingchecksum-zayovuj1"
2026/02/26 03:13:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:26 DEBUG : Local file system at /tmp/031313xi/datadir/test_ignorelistingchecksum-zayovuj1: Waiting for checks to finish
2026/02/26 03:13:26 DEBUG : Local file system at /tmp/031313xi/datadir/test_ignorelistingchecksum-zayovuj1: Waiting for transfers to finish
2026/02/26 03:13:26 DEBUG : file1.txt.75b77f44.partial: size = 19 OK
2026/02/26 03:13:26 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2026/02/26 03:13:26 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt
2026/02/26 03:13:26 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:26 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:26 ERROR : error listing: directory not found
2026/02/26 03:13:26 ERROR : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1': Failed to list "": directory not found
2026/02/26 03:13:26 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:26 INFO  : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1': Removing directory
2026/02/26 03:13:26 ERROR : Failed to rmdir: directory not found
2026/02/26 03:13:26 DEBUG : Waiting for deletions to finish
2026/02/26 03:13:26 ERROR : error listing: directory not found
2026/02/26 03:13:26 ERROR : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2': Failed to list "": directory not found
2026/02/26 03:13:26 DEBUG : removing 1 level 0 directories
2026/02/26 03:13:26 INFO  : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2': Removing directory
2026/02/26 03:13:26 ERROR : Failed to rmdir: directory not found
2026/02/26 03:13:26 ERROR : error listing: directory not found
2026/02/26 03:13:26 ERROR : error listing: directory not found
2026/02/26 03:13:26 NOTICE: checking initFs Local file system at /tmp/031313xi/initdir/test_ignorelistingchecksum-doraqas0
2026/02/26 03:13:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:27 INFO  : subdir: Making directory
2026/02/26 03:13:27 ERROR : RCLONE_TEST: Failed to copy: failed to make directory: can't create folder, create folder: Parent folder does not exist (status 404)
2026/02/26 03:13:28 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/26 03:13:28 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1': Waiting for checks to finish
2026/02/26 03:13:28 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1': Waiting for transfers to finish
2026/02/26 03:13:28 DEBUG : file1.copy2.txt: size = 0 OK
2026/02/26 03:13:28 INFO  : file1.copy2.txt: Copied (new)
2026/02/26 03:13:28 DEBUG : file1.copy1.txt: size = 0 OK
2026/02/26 03:13:28 INFO  : file1.copy1.txt: Copied (new)
2026/02/26 03:13:28 DEBUG : file1.copy4.txt: size = 0 OK
2026/02/26 03:13:28 INFO  : file1.copy4.txt: Copied (new)
2026/02/26 03:13:28 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:13:28 INFO  : file1.txt: Copied (new)
2026/02/26 03:13:29 DEBUG : file1.copy3.txt: size = 0 OK
2026/02/26 03:13:29 INFO  : file1.copy3.txt: Copied (new)
2026/02/26 03:13:29 DEBUG : file1.copy5.txt: size = 0 OK
2026/02/26 03:13:29 INFO  : file1.copy5.txt: Copied (new)
2026/02/26 03:13:29 DEBUG : subdir/file20.txt: size = 0 OK
2026/02/26 03:13:29 INFO  : subdir/file20.txt: Copied (new)
    bisync_test.go:2013: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:2013
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:506
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	failed to make directory: can't create folder, create folder: Parent folder does not exist (status 404)
        	Test:       	TestBisyncRemoteRemote/ignorelistingchecksum
        	Messages:   	setting up path1
2026/02/26 03:13:29 NOTICE: checking Path1 Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path1'
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5
    fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5
    fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Should be true
        	Test:       	TestBisyncRemoteRemote/ignorelistingchecksum
        	Messages:   	listing wrong, want
        	            	  RCLONE_TEST (109), file1.copy1.txt (0), file1.copy2.txt (0), file1.copy3.txt (0), file1.copy4.txt (0), file1.copy5.txt (0), file1.txt (0), subdir/file20.txt (0) got
        	            	  file1.copy1.txt (0), file1.copy2.txt (0), file1.copy3.txt (0), file1.copy4.txt (0), file1.copy5.txt (0), file1.txt (0), subdir/file20.txt (0)
    fstest.go:203: Not found "RCLONE_TEST"
    fstest.go:206: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncRemoteRemote/ignorelistingchecksum
        	Messages:   	1 objects not found
2026/02/26 03:14:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:02 INFO  : subdir: Making directory
2026/02/26 03:14:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/26 03:14:03 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2': Waiting for checks to finish
2026/02/26 03:14:03 DEBUG : Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2': Waiting for transfers to finish
2026/02/26 03:14:03 DEBUG : file1.copy2.txt: size = 0 OK
2026/02/26 03:14:03 INFO  : file1.copy2.txt: Copied (new)
2026/02/26 03:14:03 DEBUG : file1.copy3.txt: size = 0 OK
2026/02/26 03:14:03 INFO  : file1.copy3.txt: Copied (new)
2026/02/26 03:14:03 DEBUG : file1.copy1.txt: size = 0 OK
2026/02/26 03:14:03 INFO  : file1.copy1.txt: Copied (new)
2026/02/26 03:14:03 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/26 03:14:03 INFO  : RCLONE_TEST: Copied (new)
2026/02/26 03:14:03 DEBUG : file1.copy5.txt: size = 0 OK
2026/02/26 03:14:03 INFO  : file1.copy5.txt: Copied (new)
2026/02/26 03:14:04 DEBUG : file1.copy4.txt: size = 0 OK
2026/02/26 03:14:04 INFO  : file1.copy4.txt: Copied (new)
2026/02/26 03:14:04 DEBUG : subdir/file20.txt: size = 0 OK
2026/02/26 03:14:04 INFO  : subdir/file20.txt: Copied (new)
2026/02/26 03:14:04 DEBUG : file1.txt: size = 0 OK
2026/02/26 03:14:04 INFO  : file1.txt: Copied (new)
2026/02/26 03:14:04 NOTICE: checking path2 Internxt root 'rclone-test-hapemuc1yubi/031313xi/ignorelistingchecksum/path2'
2026/02/26 03:14:05 NOTICE: (01)  : test basic
2026/02/26 03:14:05 NOTICE: (02)  : test initial bisync
2026/02/26 03:14:05 NOTICE: (03)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
2026/02/26 03:14:07 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/RCLONE_TEST"
2026/02/26 03:14:07 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.copy1.txt"
2026/02/26 03:14:07 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.copy2.txt"
2026/02/26 03:14:07 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.copy3.txt"
2026/02/26 03:14:07 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.copy4.txt"
2026/02/26 03:14:08 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.copy5.txt"
2026/02/26 03:14:08 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/file1.txt"
2026/02/26 03:14:08 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.copy1.txt"
2026/02/26 03:14:08 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.copy2.txt"
2026/02/26 03:14:08 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.copy3.txt"
2026/02/26 03:14:09 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.copy4.txt"
2026/02/26 03:14:09 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.copy5.txt"
2026/02/26 03:14:09 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/file1.txt"
2026/02/26 03:14:10 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/RCLONE_TEST"
2026/02/26 03:14:10 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file1.txt"
2026/02/26 03:14:10 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file2.txt"
2026/02/26 03:14:10 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file3.txt"
2026/02/26 03:14:10 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file4.txt"
2026/02/26 03:14:11 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file5.txt"
2026/02/26 03:14:11 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file6.txt"
2026/02/26 03:14:11 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file7.txt"
2026/02/26 03:14:11 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path2/file8.txt"
2026/02/26 03:14:11 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/RCLONE_TEST"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file1.txt"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file2.txt"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file3.txt"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file4.txt"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file5.txt"
2026/02/26 03:14:12 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file6.txt"
2026/02/26 03:14:13 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file7.txt"
2026/02/26 03:14:13 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/backupdir/path1/file8.txt"
2026/02/26 03:14:13 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path2/subdir/file20.txt"
2026/02/26 03:14:13 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge object "031313xi/ignorelistingchecksum/path1/subdir/file20.txt"
2026/02/26 03:14:13 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/path2"
2026/02/26 03:14:14 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/path1"
2026/02/26 03:14:14 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/ignorelistingchecksum/path2/subdir"
2026/02/26 03:14:14 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/ignorelistingchecksum/path2"
2026/02/26 03:14:15 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/ignorelistingchecksum/path1/subdir"
2026/02/26 03:14:15 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/ignorelistingchecksum/path1"
2026/02/26 03:14:15 NOTICE: purge failed to rmdir "031313xi/ignorelistingchecksum/path1": directory not empty
2026/02/26 03:14:15 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/ignorelistingchecksum"
2026/02/26 03:14:15 NOTICE: purge failed to rmdir "031313xi/ignorelistingchecksum": directory not empty
2026/02/26 03:14:15 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/backupdir/path2"
2026/02/26 03:14:16 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/backupdir/path1"
2026/02/26 03:14:16 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi/backupdir"
2026/02/26 03:14:16 NOTICE: purge failed to rmdir "031313xi/backupdir": directory not empty
2026/02/26 03:14:16 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir "031313xi"
2026/02/26 03:14:16 NOTICE: purge failed to rmdir "031313xi": directory not empty
2026/02/26 03:14:16 DEBUG : Internxt root 'rclone-test-hapemuc1yubi': Purge dir ""
2026/02/26 03:14:16 NOTICE: purge failed to rmdir "": directory not empty
--- FAIL: TestBisyncRemoteRemote (64.13s)
    --- SKIP: TestBisyncRemoteRemote/backupdir (10.87s)
    --- FAIL: TestBisyncRemoteRemote/ignorelistingchecksum (41.29s)
FAIL
2026/02/26 03:14:17 ERROR : error listing: directory not found
2026/02/26 03:14:17 DEBUG : Internxt root 'rclone-test-taganuz3riza': Purge dir ""
2026/02/26 03:14:17 NOTICE: purge failed to rmdir "": directory not found
2026/02/26 03:14:17 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(max_delete_path2_force|normalization)$|^TestBisyncRemoteLocal$/^(max_delete_path1|resolve)$|^TestBisyncRemoteRemote$/^(backupdir|ignorelistingchecksum)$'" - Finished ERROR in 2m43.127811963s (try 5/5): exit status 1: Failed [TestBisyncRemoteLocal/max_delete_path1 TestBisyncLocalRemote/max_delete_path2_force TestBisyncRemoteRemote/ignorelistingchecksum]