"./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -verbose" - Starting (try 1/5) 2026/02/09 04:26:28 DEBUG : Creating backend with remote "TestInternxt:rclone-test-finoyux7roki" 2026/02/09 04:26:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/09 04:26:28 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:26:29 DEBUG : Creating backend with remote "/tmp/rclone3443695733" === RUN TestBisyncRemoteLocal 2026/02/09 04:26:29 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke" 2026/02/09 04:26:29 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:26:30 NOTICE: remote: TestInternxt:rclone-test-tehuyez8moke === RUN TestBisyncRemoteLocal/all_changed 2026/02/09 04:26:30 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de" 2026/02/09 04:26:30 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:26:30 INFO : path1: Making directory 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:639 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:432 /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: TestBisyncRemoteLocal/all_changed Messages: Mkdir path1 2026/02/09 04:26:31 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/all_changed/path1" 2026/02/09 04:26:31 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:26:32 DEBUG : Creating backend with remote "/tmp/042630de" 2026/02/09 04:26:32 DEBUG : Creating backend with remote "/tmp/042630de/all_changed/path2" 2026/02/09 04:26:32 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial" 2026/02/09 04:26:32 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_all_changed-fumurot0" 2026/02/09 04:26:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : subdir: Making directory with metadata 2026/02/09 04:26:32 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:26:32 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:26:32 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:32 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/02/09 04:26:32 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/02/09 04:26:32 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/02/09 04:26:32 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : Local file system at /tmp/042630de/initdir/test_all_changed-fumurot0: Waiting for checks to finish 2026/02/09 04:26:32 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : Local file system at /tmp/042630de/initdir/test_all_changed-fumurot0: Waiting for transfers to finish 2026/02/09 04:26:32 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/02/09 04:26:32 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:26:32 INFO : file1.txt: Copied (new) 2026/02/09 04:26:32 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/02/09 04:26:32 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:26:32 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:26:32 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:26:32 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:26:32 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:26:32 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:26:32 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:26:32 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:26:32 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:26:32 DEBUG : Waiting for deletions to finish 2026/02/09 04:26:32 ERROR : error listing: directory not found 2026/02/09 04:26:32 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/all_changed/path1': Failed to list "": directory not found 2026/02/09 04:26:32 DEBUG : removing 1 level 0 directories 2026/02/09 04:26:32 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/all_changed/path1': Removing directory 2026/02/09 04:26:33 ERROR : Failed to rmdir: directory not found 2026/02/09 04:26:33 DEBUG : Waiting for deletions to finish 2026/02/09 04:26:33 ERROR : error listing: directory not found 2026/02/09 04:26:33 ERROR : Local file system at /tmp/042630de/all_changed/path2: Failed to list "": directory not found 2026/02/09 04:26:33 DEBUG : removing 1 level 0 directories 2026/02/09 04:26:33 INFO : Local file system at /tmp/042630de/all_changed/path2: Removing directory 2026/02/09 04:26:33 ERROR : Failed to rmdir: stat /tmp/042630de/all_changed/path2: no such file or directory 2026/02/09 04:26:33 ERROR : error listing: directory not found 2026/02/09 04:26:33 ERROR : error listing: directory not found 2026/02/09 04:26:33 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_all_changed-fumurot0 2026/02/09 04:26:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:33 INFO : subdir: Making directory 2026/02/09 04:26:34 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:26:34 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/all_changed/path1': Waiting for checks to finish 2026/02/09 04:26:34 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/all_changed/path1': Waiting for transfers to finish 2026/02/09 04:26:35 DEBUG : file1.copy2.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : file1.copy1.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : file1.copy3.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : file1.copy4.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : file1.copy5.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : file1.txt: size = 0 OK 2026/02/09 04:26:35 INFO : file1.txt: Copied (new) 2026/02/09 04:26:35 DEBUG : subdir/file20.txt: size = 0 OK 2026/02/09 04:26:35 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:27:00 DEBUG : RCLONE_TEST: size = 109 OK 2026/02/09 04:27:00 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:27:00 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/all_changed/path1' 2026/02/09 04:27:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : subdir: Making directory with metadata 2026/02/09 04:27:01 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:27:01 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:27:01 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:01 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : Local file system at /tmp/042630de/all_changed/path2: Waiting for checks to finish 2026/02/09 04:27:01 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : Local file system at /tmp/042630de/all_changed/path2: Waiting for transfers to finish 2026/02/09 04:27:01 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:27:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:27:01 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:27:01 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/02/09 04:27:01 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/02/09 04:27:01 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/02/09 04:27:01 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/02/09 04:27:01 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/02/09 04:27:01 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:27:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:01 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:27:01 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:27:01 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:27:01 INFO : file1.txt: Copied (new) 2026/02/09 04:27:01 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:27:01 NOTICE: checking path2 Local file system at /tmp/042630de/all_changed/path2 2026/02/09 04:27:01 NOTICE: (01) : test all-changed 2026/02/09 04:27:01 NOTICE: (02) : test initial bisync 2026/02/09 04:27:01 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/backupdir 2026/02/09 04:27:01 INFO : path1: Making directory 2026/02/09 04:27:01 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/backupdir/path1" 2026/02/09 04:27:01 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:27:02 DEBUG : Creating backend with remote "/tmp/042630de/backupdir/path2" 2026/02/09 04:27:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/initial" 2026/02/09 04:27:02 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_backupdir-ronuxey5" 2026/02/09 04:27:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : Local file system at /tmp/042630de/initdir/test_backupdir-ronuxey5: Waiting for checks to finish 2026/02/09 04:27:02 DEBUG : Local file system at /tmp/042630de/initdir/test_backupdir-ronuxey5: Waiting for transfers to finish 2026/02/09 04:27:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:27:02 INFO : file1.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:27:02 INFO : file2.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:27:02 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:27:02 INFO : file3.txt: Copied (new) 2026/02/09 04:27:02 INFO : file5.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:27:02 INFO : file7.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:27:02 INFO : file6.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:27:02 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:27:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:27:02 INFO : file8.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:27:02 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:27:02 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:27:02 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:27:02 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:27:02 INFO : file4.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/modfiles" 2026/02/09 04:27:02 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_backupdir-wutosac6" 2026/02/09 04:27:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file10.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file11.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:02 DEBUG : Local file system at /tmp/042630de/datadir/test_backupdir-wutosac6: Waiting for checks to finish 2026/02/09 04:27:02 DEBUG : file11.txt.40d797d4.partial: size = 19 OK 2026/02/09 04:27:02 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:27:02 DEBUG : file1.txt.75b77f44.partial: size = 19 OK 2026/02/09 04:27:02 DEBUG : file10.txt.c02780cb.partial: size = 19 OK 2026/02/09 04:27:02 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:27:02 DEBUG : Local file system at /tmp/042630de/datadir/test_backupdir-wutosac6: Waiting for transfers to finish 2026/02/09 04:27:02 DEBUG : file2.txt.4526d70e.partial: size = 13 OK 2026/02/09 04:27:02 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt 2026/02/09 04:27:02 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:27:02 INFO : file11.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2026/02/09 04:27:02 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2026/02/09 04:27:02 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt 2026/02/09 04:27:02 INFO : file1.txt: Copied (new) 2026/02/09 04:27:02 INFO : file2.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2026/02/09 04:27:02 INFO : file10.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK 2026/02/09 04:27:02 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2026/02/09 04:27:02 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2026/02/09 04:27:02 INFO : file5L.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file7.txt.c1750184.partial: size = 19 OK 2026/02/09 04:27:02 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK 2026/02/09 04:27:02 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:27:02 DEBUG : file6.txt.4185169b.partial: size = 19 OK 2026/02/09 04:27:02 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2026/02/09 04:27:02 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:27:02 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2026/02/09 04:27:02 INFO : file5R.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt 2026/02/09 04:27:02 INFO : file7.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2026/02/09 04:27:02 INFO : file6.txt: Copied (new) 2026/02/09 04:27:02 DEBUG : Waiting for deletions to finish 2026/02/09 04:27:03 ERROR : error listing: directory not found 2026/02/09 04:27:03 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/backupdir/path1': Failed to list "": directory not found 2026/02/09 04:27:03 DEBUG : removing 1 level 0 directories 2026/02/09 04:27:03 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/backupdir/path1': Removing directory 2026/02/09 04:27:03 ERROR : Failed to rmdir: directory not found 2026/02/09 04:27:03 DEBUG : Waiting for deletions to finish 2026/02/09 04:27:03 ERROR : error listing: directory not found 2026/02/09 04:27:03 ERROR : Local file system at /tmp/042630de/backupdir/path2: Failed to list "": directory not found 2026/02/09 04:27:03 DEBUG : removing 1 level 0 directories 2026/02/09 04:27:03 INFO : Local file system at /tmp/042630de/backupdir/path2: Removing directory 2026/02/09 04:27:03 ERROR : Failed to rmdir: stat /tmp/042630de/backupdir/path2: no such file or directory 2026/02/09 04:27:03 ERROR : error listing: directory not found 2026/02/09 04:27:03 ERROR : error listing: directory not found 2026/02/09 04:27:03 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_backupdir-ronuxey5 2026/02/09 04:27:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:27:03 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/backupdir/path1': Waiting for checks to finish 2026/02/09 04:27:03 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/backupdir/path1': Waiting for transfers to finish 2026/02/09 04:27:04 ERROR : file1.txt: Failed to copy: failed to make directory: can't create folder, create folder: Parent folder does not exist (status 404) 2026/02/09 04:27:05 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404) 2026/02/09 04:27:05 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404) 2026/02/09 04:27:05 DEBUG : file3.txt: size = 0 OK 2026/02/09 04:27:05 INFO : file3.txt: Copied (new) 2026/02/09 04:27:05 ERROR : file5.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404) 2026/02/09 04:27:05 DEBUG : file7.txt: size = 0 OK 2026/02/09 04:27:05 INFO : file7.txt: Copied (new) 2026/02/09 04:27:05 ERROR : file8.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404) 2026/02/09 04:27:06 DEBUG : file6.txt: size = 0 OK 2026/02/09 04:27:06 INFO : file6.txt: Copied (new) 2026/02/09 04:29:10 DEBUG : pacer: low level retry 1/1 (error failed to start upload: start upload: error code: 524 (status 524)) 2026/02/09 04:29:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/09 04:29:10 DEBUG : RCLONE_TEST: Received error: failed to start upload: start upload: error code: 524 (status 524) - low level retry 0/10 2026/02/09 04:29:11 DEBUG : pacer: Reducing sleep to 15ms 2026/02/09 04:29:11 DEBUG : RCLONE_TEST: size = 109 OK 2026/02/09 04:29:11 INFO : RCLONE_TEST: 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/backupdir Messages: setting up path1 2026/02/09 04:29:11 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/backupdir/path1' 2026/02/09 04:29:11 DEBUG : pacer: Reducing sleep to 11.25ms 2026/02/09 04:29:11 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/backupdir Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0) got RCLONE_TEST (109), file3.txt (0), file6.txt (0), file7.txt (0) fstest.go:203: Not found "file5.txt" fstest.go:203: Not found "file1.txt" fstest.go:203: Not found "file2.txt" fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "file8.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 : 5 Test: TestBisyncRemoteLocal/backupdir Messages: 5 objects not found 2026/02/09 04:29:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:19 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : Local file system at /tmp/042630de/backupdir/path2: Waiting for checks to finish 2026/02/09 04:29:19 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : Local file system at /tmp/042630de/backupdir/path2: Waiting for transfers to finish 2026/02/09 04:29:19 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:29:19 INFO : file2.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:29:19 INFO : file3.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:19 INFO : file1.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:19 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:19 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:29:19 INFO : file6.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:19 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:19 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:29:19 INFO : file4.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:29:19 INFO : file5.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:29:19 INFO : file7.txt: Copied (new) 2026/02/09 04:29:19 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:29:19 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:19 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:29:19 INFO : file8.txt: Copied (new) 2026/02/09 04:29:19 NOTICE: checking path2 Local file system at /tmp/042630de/backupdir/path2 2026/02/09 04:29:19 NOTICE: (01) : test backupdir 2026/02/09 04:29:19 NOTICE: (02) : test initial bisync 2026/02/09 04:29:19 NOTICE: (03) : bisync resync backupdir1=/tmp/042630de/workdir/backupdirs/backupdir1 backupdir2=/tmp/042630de/workdir/backupdirs/backupdir2 bisync_test.go:975: backupdir test currently only works on local (it uses the workdir) === RUN TestBisyncRemoteLocal/basic 2026/02/09 04:29:19 INFO : path1: Making directory 2026/02/09 04:29:19 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/basic/path1" 2026/02/09 04:29:19 DEBUG : Config file has changed externally - reloading 2026/02/09 04:29:19 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:29:20 DEBUG : Creating backend with remote "/tmp/042630de/basic/path2" 2026/02/09 04:29:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/initial" 2026/02/09 04:29:20 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_basic-tefarog3" 2026/02/09 04:29:20 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : subdir: Making directory with metadata 2026/02/09 04:29:20 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:29:20 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:29:20 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : Local file system at /tmp/042630de/initdir/test_basic-tefarog3: Waiting for checks to finish 2026/02/09 04:29:20 DEBUG : Local file system at /tmp/042630de/initdir/test_basic-tefarog3: Waiting for transfers to finish 2026/02/09 04:29:20 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/02/09 04:29:20 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/02/09 04:29:20 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/02/09 04:29:20 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:20 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/02/09 04:29:20 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:20 INFO : file1.txt: Copied (new) 2026/02/09 04:29:20 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:20 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:29:20 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:20 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:20 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/02/09 04:29:20 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:20 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:29:20 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:29:20 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:29:20 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/modfiles" 2026/02/09 04:29:20 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_basic-hodohof2" 2026/02/09 04:29:20 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:20 DEBUG : Local file system at /tmp/042630de/datadir/test_basic-hodohof2: Waiting for checks to finish 2026/02/09 04:29:20 DEBUG : Local file system at /tmp/042630de/datadir/test_basic-hodohof2: Waiting for transfers to finish 2026/02/09 04:29:20 DEBUG : file1.txt.75b77f44.partial: size = 19 OK 2026/02/09 04:29:20 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:20 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2026/02/09 04:29:20 INFO : file1.txt: Copied (new) 2026/02/09 04:29:20 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:20 ERROR : error listing: directory not found 2026/02/09 04:29:20 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/basic/path1': Failed to list "": directory not found 2026/02/09 04:29:20 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:20 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/basic/path1': Removing directory 2026/02/09 04:29:20 ERROR : Failed to rmdir: directory not found 2026/02/09 04:29:20 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:20 ERROR : error listing: directory not found 2026/02/09 04:29:20 ERROR : Local file system at /tmp/042630de/basic/path2: Failed to list "": directory not found 2026/02/09 04:29:20 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:20 INFO : Local file system at /tmp/042630de/basic/path2: Removing directory 2026/02/09 04:29:20 ERROR : Failed to rmdir: stat /tmp/042630de/basic/path2: no such file or directory 2026/02/09 04:29:21 ERROR : error listing: directory not found 2026/02/09 04:29:21 ERROR : error listing: directory not found 2026/02/09 04:29:21 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_basic-tefarog3 2026/02/09 04:29:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:21 INFO : subdir: Making directory 2026/02/09 04:29:22 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:22 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/basic/path1': Waiting for checks to finish 2026/02/09 04:29:22 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/basic/path1': Waiting for transfers to finish 2026/02/09 04:29:22 DEBUG : file1.copy2.txt: size = 0 OK 2026/02/09 04:29:22 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:29:22 DEBUG : file1.copy3.txt: size = 0 OK 2026/02/09 04:29:22 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:29:22 DEBUG : file1.copy1.txt: size = 0 OK 2026/02/09 04:29:22 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:29:23 DEBUG : RCLONE_TEST: size = 109 OK 2026/02/09 04:29:23 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:23 DEBUG : file1.copy5.txt: size = 0 OK 2026/02/09 04:29:23 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:29:23 DEBUG : subdir/file20.txt: size = 0 OK 2026/02/09 04:29:23 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:29:23 DEBUG : file1.copy4.txt: size = 0 OK 2026/02/09 04:29:23 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:29:23 DEBUG : file1.txt: size = 0 OK 2026/02/09 04:29:23 INFO : file1.txt: Copied (new) 2026/02/09 04:29:23 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/basic/path1' 2026/02/09 04:29:24 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : subdir: Making directory with metadata 2026/02/09 04:29:24 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:29:24 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:29:24 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:24 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : Local file system at /tmp/042630de/basic/path2: Waiting for checks to finish 2026/02/09 04:29:24 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : Local file system at /tmp/042630de/basic/path2: Waiting for transfers to finish 2026/02/09 04:29:24 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/02/09 04:29:24 INFO : file1.copy1.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/02/09 04:29:24 INFO : file1.copy3.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/02/09 04:29:24 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 INFO : file1.copy2.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/02/09 04:29:24 INFO : file1.copy5.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/02/09 04:29:24 INFO : file1.copy4.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:29:24 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:24 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:24 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:24 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:24 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:24 INFO : file1.txt: Copied (new) 2026/02/09 04:29:24 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:24 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:24 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:29:24 NOTICE: checking path2 Local file system at /tmp/042630de/basic/path2 2026/02/09 04:29:24 NOTICE: (01) : test basic 2026/02/09 04:29:24 NOTICE: (02) : test initial bisync 2026/02/09 04:29:24 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/changes 2026/02/09 04:29:24 INFO : path1: Making directory 2026/02/09 04:29:24 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/changes/path1" 2026/02/09 04:29:24 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:29:25 DEBUG : Creating backend with remote "/tmp/042630de/changes/path2" 2026/02/09 04:29:25 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/initial" 2026/02/09 04:29:25 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_changes-dodozig6" 2026/02/09 04:29:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : Local file system at /tmp/042630de/initdir/test_changes-dodozig6: Waiting for checks to finish 2026/02/09 04:29:25 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:25 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:25 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:25 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:25 INFO : file1.txt: Copied (new) 2026/02/09 04:29:25 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:25 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:29:25 INFO : file2.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : Local file system at /tmp/042630de/initdir/test_changes-dodozig6: Waiting for transfers to finish 2026/02/09 04:29:25 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:29:25 INFO : file5.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:29:25 INFO : file3.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:29:25 INFO : file4.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:29:25 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:25 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:29:25 INFO : file8.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:29:25 INFO : file7.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:29:25 INFO : file6.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/modfiles" 2026/02/09 04:29:25 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_changes-fonezen9" 2026/02/09 04:29:25 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file10.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file11.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:25 DEBUG : Local file system at /tmp/042630de/datadir/test_changes-fonezen9: Waiting for checks to finish 2026/02/09 04:29:25 DEBUG : Local file system at /tmp/042630de/datadir/test_changes-fonezen9: Waiting for transfers to finish 2026/02/09 04:29:25 DEBUG : file2.txt.4526d70e.partial: size = 13 OK 2026/02/09 04:29:25 DEBUG : file1.txt.75b77f44.partial: size = 19 OK 2026/02/09 04:29:25 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2026/02/09 04:29:25 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:25 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt 2026/02/09 04:29:25 INFO : file2.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2026/02/09 04:29:25 INFO : file1.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file10.txt.c02780cb.partial: size = 19 OK 2026/02/09 04:29:25 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:25 DEBUG : file11.txt.40d797d4.partial: size = 19 OK 2026/02/09 04:29:25 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:25 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt 2026/02/09 04:29:25 INFO : file11.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK 2026/02/09 04:29:25 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2026/02/09 04:29:25 INFO : file10.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2026/02/09 04:29:25 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK 2026/02/09 04:29:25 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2026/02/09 04:29:25 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2026/02/09 04:29:25 INFO : file5R.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file6.txt.4185169b.partial: size = 19 OK 2026/02/09 04:29:25 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2026/02/09 04:29:25 INFO : file5L.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:25 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2026/02/09 04:29:25 INFO : file6.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : file7.txt.c1750184.partial: size = 19 OK 2026/02/09 04:29:25 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:29:25 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt 2026/02/09 04:29:25 INFO : file7.txt: Copied (new) 2026/02/09 04:29:25 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:25 ERROR : error listing: directory not found 2026/02/09 04:29:25 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/changes/path1': Failed to list "": directory not found 2026/02/09 04:29:25 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:25 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/changes/path1': Removing directory 2026/02/09 04:29:25 ERROR : Failed to rmdir: directory not found 2026/02/09 04:29:25 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:25 ERROR : error listing: directory not found 2026/02/09 04:29:25 ERROR : Local file system at /tmp/042630de/changes/path2: Failed to list "": directory not found 2026/02/09 04:29:25 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:25 INFO : Local file system at /tmp/042630de/changes/path2: Removing directory 2026/02/09 04:29:25 ERROR : Failed to rmdir: stat /tmp/042630de/changes/path2: no such file or directory 2026/02/09 04:29:26 ERROR : error listing: directory not found 2026/02/09 04:29:26 ERROR : error listing: directory not found 2026/02/09 04:29:26 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_changes-dodozig6 2026/02/09 04:29:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:26 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/changes/path1': Waiting for checks to finish 2026/02/09 04:29:26 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/changes/path1': Waiting for transfers to finish 2026/02/09 04:29:27 DEBUG : file3.txt: size = 0 OK 2026/02/09 04:29:27 INFO : file3.txt: Copied (new) 2026/02/09 04:29:27 DEBUG : file2.txt: size = 0 OK 2026/02/09 04:29:27 INFO : file2.txt: Copied (new) 2026/02/09 04:29:28 DEBUG : file1.txt: size = 0 OK 2026/02/09 04:29:28 INFO : file1.txt: Copied (new) 2026/02/09 04:29:28 DEBUG : RCLONE_TEST: size = 109 OK 2026/02/09 04:29:28 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:28 DEBUG : file4.txt: size = 0 OK 2026/02/09 04:29:28 INFO : file4.txt: Copied (new) 2026/02/09 04:29:28 DEBUG : file5.txt: size = 0 OK 2026/02/09 04:29:28 INFO : file5.txt: Copied (new) 2026/02/09 04:29:28 ERROR : file6.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:28 ERROR : file7.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:28 ERROR : file8.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 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: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/changes Messages: setting up path1 2026/02/09 04:29:28 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/changes/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/changes Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0) got RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0) fstest.go:203: Not found "file7.txt" fstest.go:203: Not found "file6.txt" fstest.go:203: Not found "file8.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 : 3 Test: TestBisyncRemoteLocal/changes Messages: 3 objects not found 2026/02/09 04:29:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:36 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : Local file system at /tmp/042630de/changes/path2: Waiting for checks to finish 2026/02/09 04:29:36 DEBUG : Local file system at /tmp/042630de/changes/path2: Waiting for transfers to finish 2026/02/09 04:29:36 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:36 INFO : file1.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:29:36 INFO : file2.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:29:36 INFO : file3.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:36 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:36 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:29:36 INFO : file4.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:29:36 INFO : file5.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:29:36 INFO : file6.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:36 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:36 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:29:36 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:36 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:29:36 INFO : file7.txt: Copied (new) 2026/02/09 04:29:36 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:29:36 INFO : file8.txt: Copied (new) 2026/02/09 04:29:36 NOTICE: checking path2 Local file system at /tmp/042630de/changes/path2 2026/02/09 04:29:36 NOTICE: (01) : test changes 2026/02/09 04:29:36 NOTICE: (02) : test initial bisync 2026/02/09 04:29:36 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/check_access 2026/02/09 04:29:36 INFO : path1: Making directory 2026/02/09 04:29:36 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/check_access/path1" 2026/02/09 04:29:36 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:29:37 DEBUG : Creating backend with remote "/tmp/042630de/check_access/path2" 2026/02/09 04:29:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/initial" 2026/02/09 04:29:37 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_check_access-yaricoz8" 2026/02/09 04:29:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : subdir: Making directory with metadata 2026/02/09 04:29:37 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:29:37 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:29:37 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:29:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:37 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:29:37 DEBUG : Local file system at /tmp/042630de/initdir/test_check_access-yaricoz8: Waiting for checks to finish 2026/02/09 04:29:37 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:29:37 DEBUG : Local file system at /tmp/042630de/initdir/test_check_access-yaricoz8: Waiting for transfers to finish 2026/02/09 04:29:37 INFO : file1.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:37 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:29:37 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:37 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:29:37 INFO : file2.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:29:37 INFO : file3.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:29:37 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:29:37 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:37 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:37 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:29:37 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:29:37 INFO : file4.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:29:37 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:29:37 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:29:37 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:29:37 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/02/09 04:29:37 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:29:37 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/02/09 04:29:37 INFO : subdir/RCLONE_TEST: Copied (new) 2026/02/09 04:29:37 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:29:37 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/modfiles" 2026/02/09 04:29:37 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_check_access-sicidut1" 2026/02/09 04:29:37 DEBUG : hold.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:37 DEBUG : Local file system at /tmp/042630de/datadir/test_check_access-sicidut1: Waiting for checks to finish 2026/02/09 04:29:37 DEBUG : Local file system at /tmp/042630de/datadir/test_check_access-sicidut1: Waiting for transfers to finish 2026/02/09 04:29:37 DEBUG : hold.txt.83686dab.partial: size = 59 OK 2026/02/09 04:29:37 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2026/02/09 04:29:37 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt 2026/02/09 04:29:37 INFO : hold.txt: Copied (new) 2026/02/09 04:29:37 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:38 ERROR : error listing: directory not found 2026/02/09 04:29:38 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access/path1': Failed to list "": directory not found 2026/02/09 04:29:38 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:38 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access/path1': Removing directory 2026/02/09 04:29:38 ERROR : Failed to rmdir: directory not found 2026/02/09 04:29:38 DEBUG : Waiting for deletions to finish 2026/02/09 04:29:38 ERROR : error listing: directory not found 2026/02/09 04:29:38 ERROR : Local file system at /tmp/042630de/check_access/path2: Failed to list "": directory not found 2026/02/09 04:29:38 DEBUG : removing 1 level 0 directories 2026/02/09 04:29:38 INFO : Local file system at /tmp/042630de/check_access/path2: Removing directory 2026/02/09 04:29:38 ERROR : Failed to rmdir: stat /tmp/042630de/check_access/path2: no such file or directory 2026/02/09 04:29:38 ERROR : error listing: directory not found 2026/02/09 04:29:38 ERROR : error listing: directory not found 2026/02/09 04:29:38 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_check_access-yaricoz8 2026/02/09 04:29:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:38 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:38 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:38 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:38 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:38 INFO : subdir: Making directory 2026/02/09 04:29:39 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:29:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:29:39 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access/path1': Waiting for checks to finish 2026/02/09 04:29:39 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access/path1': Waiting for transfers to finish 2026/02/09 04:29:39 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:39 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:39 ERROR : file3.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:40 ERROR : subdir/file20.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:40 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:51 ERROR : RCLONE_TEST: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:29:51 ERROR : subdir/RCLONE_TEST: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 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 file metadata: create meta: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/check_access Messages: setting up path1 2026/02/09 04:29:51 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/check_access/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/check_access Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), subdir/RCLONE_TEST (109), subdir/file20.txt (0) got fstest.go:203: Not found "file3.txt" fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "subdir/RCLONE_TEST" fstest.go:203: Not found "subdir/file20.txt" fstest.go:203: Not found "RCLONE_TEST" fstest.go:203: Not found "file1.txt" 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:508 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 Error: Not equal: expected: 0 actual : 7 Test: TestBisyncRemoteLocal/check_access Messages: 7 objects not found 2026/02/09 04:30:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : subdir: Making directory with metadata 2026/02/09 04:30:00 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:00 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:30:00 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:30:00 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:30:00 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:00 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:00 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:30:00 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:00 INFO : file1.txt: Copied (new) 2026/02/09 04:30:00 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:30:00 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:00 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:30:00 INFO : file2.txt: Copied (new) 2026/02/09 04:30:00 DEBUG : Local file system at /tmp/042630de/check_access/path2: Waiting for checks to finish 2026/02/09 04:30:00 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:30:00 DEBUG : Local file system at /tmp/042630de/check_access/path2: Waiting for transfers to finish 2026/02/09 04:30:00 INFO : file3.txt: Copied (new) 2026/02/09 04:30:00 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:30:00 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:00 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:30:00 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:30:00 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:00 INFO : file4.txt: Copied (new) 2026/02/09 04:30:00 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:30:00 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:30:00 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:30:00 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:00 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:30:00 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:30:00 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/02/09 04:30:00 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:00 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/02/09 04:30:00 INFO : subdir/RCLONE_TEST: Copied (new) 2026/02/09 04:30:00 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:30:00 NOTICE: checking path2 Local file system at /tmp/042630de/check_access/path2 2026/02/09 04:30:00 NOTICE: (01) : test check-access 2026/02/09 04:30:00 NOTICE: (02) : test initial bisync 2026/02/09 04:30:00 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/check_access_filters 2026/02/09 04:30:00 INFO : path1: Making directory 2026/02/09 04:30:00 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/check_access_filters/path1" 2026/02/09 04:30:00 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:30:01 DEBUG : Creating backend with remote "/tmp/042630de/check_access_filters/path2" 2026/02/09 04:30:01 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial" 2026/02/09 04:30:01 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_check_access_filters-sezodel5" 2026/02/09 04:30:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir-not: Making directory with metadata 2026/02/09 04:30:01 INFO : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not 2026/02/09 04:30:01 DEBUG : subdir: Making directory with metadata 2026/02/09 04:30:01 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:30:01 DEBUG : subdirX: Making directory with metadata 2026/02/09 04:30:01 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 INFO : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdirX", newDst=subdirX 2026/02/09 04:30:01 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:30:01 INFO : file2.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1: Making directory with metadata 2026/02/09 04:30:01 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 INFO : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1 2026/02/09 04:30:01 INFO : file3.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:30:01 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 INFO : file1.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:30:01 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 INFO : file4.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir/subdirA: Making directory with metadata 2026/02/09 04:30:01 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 INFO : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt 2026/02/09 04:30:01 INFO : subdirX/file20.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA 2026/02/09 04:30:01 DEBUG : subdir/subdirB: Making directory with metadata 2026/02/09 04:30:01 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:30:01 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 INFO : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB 2026/02/09 04:30:01 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2: Making directory with metadata 2026/02/09 04:30:01 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 INFO : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK 2026/02/09 04:30:01 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2 2026/02/09 04:30:01 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:30:01 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:30:01 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : Local file system at /tmp/042630de/initdir/test_check_access_filters-sezodel5: Waiting for checks to finish 2026/02/09 04:30:01 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : Local file system at /tmp/042630de/initdir/test_check_access_filters-sezodel5: Waiting for transfers to finish 2026/02/09 04:30:01 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdir/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt 2026/02/09 04:30:01 INFO : subdir-not/file20.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST 2026/02/09 04:30:01 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK 2026/02/09 04:30:01 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt 2026/02/09 04:30:01 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt 2026/02/09 04:30:01 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK 2026/02/09 04:30:01 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:30:01 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK 2026/02/09 04:30:01 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:30:01 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt 2026/02/09 04:30:01 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST 2026/02/09 04:30:01 INFO : subdir/subdirA/RCLONE_TEST: Copied (new) 2026/02/09 04:30:01 INFO : subdir-not/subdir-not2: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdir/subdirB: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdir/subdirA: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdirX/subdirX1: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdirX: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdir-not: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:30:01 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles" 2026/02/09 04:30:01 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_check_access_filters-pomehal6" 2026/02/09 04:30:01 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:01 DEBUG : Local file system at /tmp/042630de/datadir/test_check_access_filters-pomehal6: Waiting for checks to finish 2026/02/09 04:30:01 DEBUG : Local file system at /tmp/042630de/datadir/test_check_access_filters-pomehal6: Waiting for transfers to finish 2026/02/09 04:30:01 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK 2026/02/09 04:30:01 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK 2026/02/09 04:30:01 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt 2026/02/09 04:30:01 INFO : exclude-other-filtersfile.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK 2026/02/09 04:30:01 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK 2026/02/09 04:30:01 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt 2026/02/09 04:30:01 INFO : include-other-filtersfile.txt: Copied (new) 2026/02/09 04:30:01 DEBUG : Waiting for deletions to finish 2026/02/09 04:30:01 ERROR : error listing: directory not found 2026/02/09 04:30:01 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access_filters/path1': Failed to list "": directory not found 2026/02/09 04:30:01 DEBUG : removing 1 level 0 directories 2026/02/09 04:30:01 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access_filters/path1': Removing directory 2026/02/09 04:30:01 ERROR : Failed to rmdir: directory not found 2026/02/09 04:30:01 DEBUG : Waiting for deletions to finish 2026/02/09 04:30:01 ERROR : error listing: directory not found 2026/02/09 04:30:01 ERROR : Local file system at /tmp/042630de/check_access_filters/path2: Failed to list "": directory not found 2026/02/09 04:30:01 DEBUG : removing 1 level 0 directories 2026/02/09 04:30:01 INFO : Local file system at /tmp/042630de/check_access_filters/path2: Removing directory 2026/02/09 04:30:01 ERROR : Failed to rmdir: stat /tmp/042630de/check_access_filters/path2: no such file or directory 2026/02/09 04:30:02 ERROR : error listing: directory not found 2026/02/09 04:30:02 ERROR : error listing: directory not found 2026/02/09 04:30:02 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_check_access_filters-sezodel5 2026/02/09 04:30:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:02 INFO : subdir-not: Making directory 2026/02/09 04:30:03 INFO : subdir: Making directory 2026/02/09 04:30:03 INFO : subdirX: Making directory 2026/02/09 04:30:03 ERROR : file3.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:30:03 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:30:03 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:30:03 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:03 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:03 INFO : subdirX/subdirX1: Making directory 2026/02/09 04:30:03 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:03 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:03 INFO : subdir-not/subdir-not2: Making directory 2026/02/09 04:30:03 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:03 INFO : subdir/subdirA: Making directory 2026/02/09 04:30:04 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:04 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:04 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:04 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:04 INFO : subdir/subdirB: Making directory 2026/02/09 04:30:04 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:30:05 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:05 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:05 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:30:05 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:30:05 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access_filters/path1': Waiting for checks to finish 2026/02/09 04:30:05 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_access_filters/path1': Waiting for transfers to finish 2026/02/09 04:30:05 ERROR : subdirX/file20.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:30:05 ERROR : subdir-not/file20.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:07 DEBUG : pacer: low level retry 1/1 (error failed to start upload: start upload: error code: 524 (status 524)) 2026/02/09 04:32:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/09 04:32:07 DEBUG : RCLONE_TEST: Received error: failed to start upload: start upload: error code: 524 (status 524) - low level retry 0/10 2026/02/09 04:32:09 DEBUG : pacer: Reducing sleep to 15ms 2026/02/09 04:32:09 DEBUG : RCLONE_TEST: size = 109 OK 2026/02/09 04:32:09 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:32:10 DEBUG : pacer: Reducing sleep to 11.25ms 2026/02/09 04:32:10 DEBUG : subdir/file20.txt: size = 0 OK 2026/02/09 04:32:10 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:32:10 DEBUG : pacer: low level retry 1/1 (error failed to start upload: start upload: error code: 524 (status 524)) 2026/02/09 04:32:10 DEBUG : pacer: Rate limited, increasing sleep to 22.5ms 2026/02/09 04:32:10 DEBUG : subdirX/RCLONE_TEST: Received error: failed to start upload: start upload: error code: 524 (status 524) - low level retry 0/10 2026/02/09 04:32:10 DEBUG : pacer: low level retry 1/1 (error failed to start upload: start upload: error code: 524 (status 524)) 2026/02/09 04:32:10 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2026/02/09 04:32:10 DEBUG : subdir-not/RCLONE_TEST: Received error: failed to start upload: start upload: error code: 524 (status 524) - low level retry 0/10 2026/02/09 04:32:10 DEBUG : pacer: low level retry 1/1 (error failed to start upload: start upload: error code: 524 (status 524)) 2026/02/09 04:32:10 DEBUG : pacer: Rate limited, increasing sleep to 90ms 2026/02/09 04:32:10 DEBUG : subdir/RCLONE_TEST: Received error: failed to start upload: start upload: error code: 524 (status 524) - low level retry 0/10 2026/02/09 04:32:10 DEBUG : pacer: Reducing sleep to 67.5ms 2026/02/09 04:32:10 DEBUG : subdirX/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:10 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/02/09 04:32:10 DEBUG : pacer: Reducing sleep to 50.625ms 2026/02/09 04:32:10 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:10 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/02/09 04:32:10 DEBUG : pacer: Reducing sleep to 37.96875ms 2026/02/09 04:32:10 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:10 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/02/09 04:32:11 DEBUG : pacer: Reducing sleep to 28.476562ms 2026/02/09 04:32:11 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK 2026/02/09 04:32:11 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/02/09 04:32:11 DEBUG : pacer: Reducing sleep to 21.357421ms 2026/02/09 04:32:11 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:11 INFO : subdir/RCLONE_TEST: Copied (new) 2026/02/09 04:32:11 DEBUG : pacer: Reducing sleep to 16.018065ms 2026/02/09 04:32:11 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK 2026/02/09 04:32:11 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/02/09 04:32:11 DEBUG : pacer: Reducing sleep to 12.013548ms 2026/02/09 04:32:11 DEBUG : subdir/subdirB/file30.txt: size = 0 OK 2026/02/09 04:32:11 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/02/09 04:32:11 DEBUG : pacer: Reducing sleep to 10ms 2026/02/09 04:32:11 DEBUG : subdir/subdirA/file30.txt: size = 0 OK 2026/02/09 04:32:11 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/02/09 04:32:11 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:11 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/02/09 04:32:12 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:12 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/02/09 04:32:12 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK 2026/02/09 04:32:12 INFO : subdir/subdirA/RCLONE_TEST: 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: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/check_access_filters Messages: setting up path1 2026/02/09 04:32:12 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/check_access_filters/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/check_access_filters Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), subdir-not/RCLONE_TEST (109), subdir-not/file20.txt (0), subdir-not/subdir-not2/RCLONE_TEST (109), subdir-not/subdir-not2/file30.txt (0), subdir/RCLONE_TEST (109), subdir/file20.txt (0), subdir/subdirA/RCLONE_TEST (109), subdir/subdirA/file30.txt (0), subdir/subdirB/RCLONE_TEST (109), subdir/subdirB/file30.txt (0), subdirX/RCLONE_TEST (109), subdirX/file20.txt (0), subdirX/subdirX1/RCLONE_TEST (109), subdirX/subdirX1/file30.txt (0) got RCLONE_TEST (109), subdir-not/RCLONE_TEST (109), subdir-not/subdir-not2/RCLONE_TEST (109), subdir-not/subdir-not2/file30.txt (0), subdir/RCLONE_TEST (109), subdir/file20.txt (0), subdir/subdirA/RCLONE_TEST (109), subdir/subdirA/file30.txt (0), subdir/subdirB/RCLONE_TEST (109), subdir/subdirB/file30.txt (0), subdirX/RCLONE_TEST (109), subdirX/subdirX1/RCLONE_TEST (109), subdirX/subdirX1/file30.txt (0) fstest.go:203: Not found "file1.txt" fstest.go:203: Not found "file2.txt" fstest.go:203: Not found "subdir-not/file20.txt" fstest.go:203: Not found "file3.txt" fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "subdirX/file20.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 : 6 Test: TestBisyncRemoteLocal/check_access_filters Messages: 6 objects not found 2026/02/09 04:32:22 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir-not: Making directory with metadata 2026/02/09 04:32:22 INFO : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not 2026/02/09 04:32:22 DEBUG : subdir: Making directory with metadata 2026/02/09 04:32:22 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:32:22 DEBUG : subdirX: Making directory with metadata 2026/02/09 04:32:22 INFO : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdirX", newDst=subdirX 2026/02/09 04:32:22 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1: Making directory with metadata 2026/02/09 04:32:22 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2: Making directory with metadata 2026/02/09 04:32:22 INFO : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:22 INFO : file1.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir/subdirA: Making directory with metadata 2026/02/09 04:32:22 INFO : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA 2026/02/09 04:32:22 DEBUG : subdir/subdirB: Making directory with metadata 2026/02/09 04:32:22 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 INFO : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB 2026/02/09 04:32:22 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:22 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 INFO : file4.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:22 INFO : file3.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:22 INFO : file2.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:32:22 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 INFO : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:22 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1 2026/02/09 04:32:22 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : Local file system at /tmp/042630de/check_access_filters/path2: Waiting for checks to finish 2026/02/09 04:32:22 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : Local file system at /tmp/042630de/check_access_filters/path2: Waiting for transfers to finish 2026/02/09 04:32:22 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt 2026/02/09 04:32:22 INFO : subdir-not/file20.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt 2026/02/09 04:32:22 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/02/09 04:32:22 INFO : subdirX/file20.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:32:22 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdir/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt 2026/02/09 04:32:22 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdir/subdirA/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt 2026/02/09 04:32:22 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt 2026/02/09 04:32:22 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/02/09 04:32:22 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:22 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST 2026/02/09 04:32:22 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/02/09 04:32:22 INFO : subdirX/subdirX1: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdir/subdirB: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdir/subdirA: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdir-not/subdir-not2: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdir-not: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 INFO : subdirX: Set directory modification time (using SetModTime) 2026/02/09 04:32:22 NOTICE: checking path2 Local file system at /tmp/042630de/check_access_filters/path2 2026/02/09 04:32:22 NOTICE: (01) : test check-access-filters 2026/02/09 04:32:22 NOTICE: (02) : test EXCLUDE - OTHER TESTS 2026/02/09 04:32:22 NOTICE: (03) : copy-file /tmp/042630de/datadir/test_check_access_filters-pomehal6/exclude-other-filtersfile.txt /tmp/042630de/workdir/ 2026/02/09 04:32:22 DEBUG : copyFile "/tmp/042630de/datadir/test_check_access_filters-pomehal6/exclude-other-filtersfile.txt" to "/tmp/042630de/workdir/" as "" 2026/02/09 04:32:22 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_check_access_filters-pomehal6/exclude-other-filtersfile.txt" 2026/02/09 04:32:22 DEBUG : Config file has changed externally - reloading 2026/02/09 04:32:22 DEBUG : Creating backend with remote "/tmp/042630de/workdir/" 2026/02/09 04:32:22 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/042630de/workdir" as "exclude-other-filtersfile.txt" 2026/02/09 04:32:22 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:22 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK 2026/02/09 04:32:22 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK 2026/02/09 04:32:22 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt 2026/02/09 04:32:22 INFO : exclude-other-filtersfile.txt: Copied (new) 2026/02/09 04:32:22 NOTICE: (04) : test resync to get the filters file md5 built. 2026/02/09 04:32:22 NOTICE: (05) : bisync resync filters-file=/tmp/042630de/workdir/exclude-other-filtersfile.txt bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/check_filename 2026/02/09 04:32:22 INFO : path1: Making directory 2026/02/09 04:32:22 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/check_filename/path1" 2026/02/09 04:32:22 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:32:23 DEBUG : Creating backend with remote "/tmp/042630de/check_filename/path2" 2026/02/09 04:32:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/initial" 2026/02/09 04:32:23 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_check_filename-fegarov4" 2026/02/09 04:32:23 DEBUG : .chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : subdir: Making directory with metadata 2026/02/09 04:32:23 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:23 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:32:23 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:23 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:23 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:23 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:23 INFO : file1.txt: Copied (new) 2026/02/09 04:32:23 DEBUG : Local file system at /tmp/042630de/initdir/test_check_filename-fegarov4: Waiting for checks to finish 2026/02/09 04:32:23 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:23 DEBUG : Local file system at /tmp/042630de/initdir/test_check_filename-fegarov4: Waiting for transfers to finish 2026/02/09 04:32:23 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:23 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:23 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:24 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:24 INFO : file4.txt: Copied (new) 2026/02/09 04:32:24 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:24 INFO : file2.txt: Copied (new) 2026/02/09 04:32:24 DEBUG : .chk_file.b7f3f5bf.partial: size = 109 OK 2026/02/09 04:32:24 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:24 DEBUG : subdir/.chk_file.6dc50fe3.partial: size = 109 OK 2026/02/09 04:32:24 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:24 DEBUG : .chk_file.b7f3f5bf.partial: renamed to: .chk_file 2026/02/09 04:32:24 INFO : .chk_file: Copied (new) 2026/02/09 04:32:24 DEBUG : subdir/.chk_file.6dc50fe3.partial: renamed to: subdir/.chk_file 2026/02/09 04:32:24 INFO : subdir/.chk_file: Copied (new) 2026/02/09 04:32:24 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:32:24 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:24 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:32:24 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:32:24 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:24 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:24 INFO : file3.txt: Copied (new) 2026/02/09 04:32:24 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:32:24 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/modfiles" 2026/02/09 04:32:24 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_check_filename-dozefim3" 2026/02/09 04:32:24 DEBUG : hold.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:24 DEBUG : Local file system at /tmp/042630de/datadir/test_check_filename-dozefim3: Waiting for checks to finish 2026/02/09 04:32:24 DEBUG : Local file system at /tmp/042630de/datadir/test_check_filename-dozefim3: Waiting for transfers to finish 2026/02/09 04:32:24 DEBUG : hold.txt.83686dab.partial: size = 59 OK 2026/02/09 04:32:24 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2026/02/09 04:32:24 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt 2026/02/09 04:32:24 INFO : hold.txt: Copied (new) 2026/02/09 04:32:24 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:24 ERROR : error listing: directory not found 2026/02/09 04:32:24 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/check_filename/path1': Failed to list "": directory not found 2026/02/09 04:32:24 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:24 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/check_filename/path1': Removing directory 2026/02/09 04:32:24 ERROR : Failed to rmdir: directory not found 2026/02/09 04:32:24 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:24 ERROR : error listing: directory not found 2026/02/09 04:32:24 ERROR : Local file system at /tmp/042630de/check_filename/path2: Failed to list "": directory not found 2026/02/09 04:32:24 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:24 INFO : Local file system at /tmp/042630de/check_filename/path2: Removing directory 2026/02/09 04:32:24 ERROR : Failed to rmdir: stat /tmp/042630de/check_filename/path2: no such file or directory 2026/02/09 04:32:24 ERROR : error listing: directory not found 2026/02/09 04:32:24 ERROR : error listing: directory not found 2026/02/09 04:32:24 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_check_filename-fegarov4 2026/02/09 04:32:24 DEBUG : .chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:24 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:24 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:24 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:24 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:24 INFO : subdir: Making directory 2026/02/09 04:32:25 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:25 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:25 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_filename/path1': Waiting for checks to finish 2026/02/09 04:32:25 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_filename/path1': Waiting for transfers to finish 2026/02/09 04:32:26 DEBUG : file3.txt: size = 0 OK 2026/02/09 04:32:26 INFO : file3.txt: Copied (new) 2026/02/09 04:32:26 DEBUG : file2.txt: size = 0 OK 2026/02/09 04:32:26 INFO : file2.txt: Copied (new) 2026/02/09 04:32:26 DEBUG : file1.txt: size = 0 OK 2026/02/09 04:32:26 INFO : file1.txt: Copied (new) 2026/02/09 04:32:26 ERROR : .chk_file: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:26 ERROR : subdir/file20.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:26 ERROR : subdir/.chk_file: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:26 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 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: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/check_filename Messages: setting up path1 2026/02/09 04:32:26 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/check_filename/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/check_filename Messages: listing wrong, want .chk_file (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), subdir/.chk_file (109), subdir/file20.txt (0) got file1.txt (0), file2.txt (0), file3.txt (0) fstest.go:203: Not found ".chk_file" fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "subdir/.chk_file" fstest.go:203: Not found "subdir/file20.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 : 4 Test: TestBisyncRemoteLocal/check_filename Messages: 4 objects not found 2026/02/09 04:32:35 DEBUG : .chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : subdir: Making directory with metadata 2026/02/09 04:32:35 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/02/09 04:32:35 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/02/09 04:32:35 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:35 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:35 DEBUG : Local file system at /tmp/042630de/check_filename/path2: Waiting for checks to finish 2026/02/09 04:32:35 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:35 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:35 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:35 DEBUG : Local file system at /tmp/042630de/check_filename/path2: Waiting for transfers to finish 2026/02/09 04:32:35 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:35 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:35 INFO : file1.txt: Copied (new) 2026/02/09 04:32:35 DEBUG : .chk_file.b7f3f5bf.partial: size = 109 OK 2026/02/09 04:32:35 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:35 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:35 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:35 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:35 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:35 INFO : file3.txt: Copied (new) 2026/02/09 04:32:35 DEBUG : .chk_file.b7f3f5bf.partial: renamed to: .chk_file 2026/02/09 04:32:35 INFO : .chk_file: Copied (new) 2026/02/09 04:32:35 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:35 INFO : file4.txt: Copied (new) 2026/02/09 04:32:35 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/02/09 04:32:35 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:35 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/02/09 04:32:35 INFO : subdir/file20.txt: Copied (new) 2026/02/09 04:32:35 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:35 INFO : file2.txt: Copied (new) 2026/02/09 04:32:35 DEBUG : subdir/.chk_file.6dc50fe3.partial: size = 109 OK 2026/02/09 04:32:35 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:35 DEBUG : subdir/.chk_file.6dc50fe3.partial: renamed to: subdir/.chk_file 2026/02/09 04:32:35 INFO : subdir/.chk_file: Copied (new) 2026/02/09 04:32:35 INFO : subdir: Set directory modification time (using SetModTime) 2026/02/09 04:32:35 NOTICE: checking path2 Local file system at /tmp/042630de/check_filename/path2 2026/02/09 04:32:35 NOTICE: (01) : test check-filename 2026/02/09 04:32:35 NOTICE: (02) : test initial bisync 2026/02/09 04:32:35 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/check_sync 2026/02/09 04:32:35 INFO : path1: Making directory 2026/02/09 04:32:35 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/check_sync/path1" 2026/02/09 04:32:35 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:32:36 DEBUG : Creating backend with remote "/tmp/042630de/check_sync/path2" 2026/02/09 04:32:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_sync/initial" 2026/02/09 04:32:36 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_check_sync-numeyus3" 2026/02/09 04:32:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:32:36 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:36 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:32:36 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:32:36 DEBUG : Local file system at /tmp/042630de/initdir/test_check_sync-numeyus3: Waiting for checks to finish 2026/02/09 04:32:36 DEBUG : Local file system at /tmp/042630de/initdir/test_check_sync-numeyus3: Waiting for transfers to finish 2026/02/09 04:32:36 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:36 INFO : file1.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:36 INFO : file2.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:36 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:36 INFO : file3.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:32:36 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:36 INFO : file4.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:32:36 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:36 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:32:36 INFO : file8.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:32:36 INFO : file6.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:32:36 INFO : file5.txt: Copied (new) 2026/02/09 04:32:36 INFO : file7.txt: Copied (new) 2026/02/09 04:32:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_sync/modfiles" 2026/02/09 04:32:36 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_check_sync-kecuyit2" 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path1.lst: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path2.lst: Need to transfer - File not found at Destination 2026/02/09 04:32:36 DEBUG : Local file system at /tmp/042630de/datadir/test_check_sync-kecuyit2: Waiting for checks to finish 2026/02/09 04:32:36 DEBUG : Local file system at /tmp/042630de/datadir/test_check_sync-kecuyit2: Waiting for transfers to finish 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path1.lst.ba8cd101.partial: size = 792 OK 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path1.lst: md5 = fdb18e0dff6961813e12c57227d2e854 OK 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path1.lst.ba8cd101.partial: renamed to: _testdir_path1.._testdir_path2.path1.lst 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path2.lst.f764d166.partial: size = 792 OK 2026/02/09 04:32:36 INFO : _testdir_path1.._testdir_path2.path1.lst: Copied (new) 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path2.lst: md5 = d6ce44a26af9c2110b1750fe88dc4b69 OK 2026/02/09 04:32:36 DEBUG : _testdir_path1.._testdir_path2.path2.lst.f764d166.partial: renamed to: _testdir_path1.._testdir_path2.path2.lst 2026/02/09 04:32:36 INFO : _testdir_path1.._testdir_path2.path2.lst: Copied (new) 2026/02/09 04:32:36 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:36 ERROR : error listing: directory not found 2026/02/09 04:32:36 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/check_sync/path1': Failed to list "": directory not found 2026/02/09 04:32:36 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:36 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/check_sync/path1': Removing directory 2026/02/09 04:32:37 ERROR : Failed to rmdir: directory not found 2026/02/09 04:32:37 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:37 ERROR : error listing: directory not found 2026/02/09 04:32:37 ERROR : Local file system at /tmp/042630de/check_sync/path2: Failed to list "": directory not found 2026/02/09 04:32:37 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:37 INFO : Local file system at /tmp/042630de/check_sync/path2: Removing directory 2026/02/09 04:32:37 ERROR : Failed to rmdir: stat /tmp/042630de/check_sync/path2: no such file or directory 2026/02/09 04:32:37 ERROR : error listing: directory not found 2026/02/09 04:32:37 ERROR : error listing: directory not found 2026/02/09 04:32:37 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_check_sync-numeyus3 2026/02/09 04:32:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:37 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_sync/path1': Waiting for checks to finish 2026/02/09 04:32:37 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/check_sync/path1': Waiting for transfers to finish 2026/02/09 04:32:38 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file3.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file5.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file6.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file8.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:38 ERROR : file7.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:39 ERROR : RCLONE_TEST: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 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 file metadata: create meta: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/check_sync Messages: setting up path1 2026/02/09 04:32:39 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/check_sync/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/check_sync Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0) got fstest.go:203: Not found "file1.txt" fstest.go:203: Not found "file2.txt" fstest.go:203: Not found "file3.txt" fstest.go:203: Not found "file5.txt" fstest.go:203: Not found "file6.txt" fstest.go:203: Not found "RCLONE_TEST" fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "file7.txt" fstest.go:203: Not found "file8.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 : 9 Test: TestBisyncRemoteLocal/check_sync Messages: 9 objects not found 2026/02/09 04:32:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:46 DEBUG : Local file system at /tmp/042630de/check_sync/path2: Waiting for checks to finish 2026/02/09 04:32:46 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : Local file system at /tmp/042630de/check_sync/path2: Waiting for transfers to finish 2026/02/09 04:32:46 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:46 INFO : file1.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:46 INFO : file2.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:46 INFO : file3.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:46 INFO : file4.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:32:46 INFO : file7.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:32:46 INFO : file5.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:32:46 INFO : file6.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:32:46 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:46 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:32:46 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:32:46 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:46 INFO : file8.txt: Copied (new) 2026/02/09 04:32:46 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:32:46 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:32:46 NOTICE: checking path2 Local file system at /tmp/042630de/check_sync/path2 2026/02/09 04:32:46 NOTICE: (01) : test check-sync 2026/02/09 04:32:46 NOTICE: (02) : test initial bisync 2026/02/09 04:32:46 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/compare_all 2026/02/09 04:32:46 INFO : path1: Making directory 2026/02/09 04:32:46 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/compare_all/path1" 2026/02/09 04:32:46 DEBUG : Config file has changed externally - reloading 2026/02/09 04:32:47 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:32:48 DEBUG : Creating backend with remote "/tmp/042630de/compare_all/path2" 2026/02/09 04:32:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_compare_all/initial" 2026/02/09 04:32:48 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_compare_all-jerazur2" 2026/02/09 04:32:48 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:32:48 INFO : file1.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:32:48 INFO : file3.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:32:48 INFO : file2.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : Local file system at /tmp/042630de/initdir/test_compare_all-jerazur2: Waiting for checks to finish 2026/02/09 04:32:48 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : Local file system at /tmp/042630de/initdir/test_compare_all-jerazur2: Waiting for transfers to finish 2026/02/09 04:32:48 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:32:48 INFO : file5.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:32:48 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:32:48 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:32:48 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:32:48 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 INFO : file6.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:32:48 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:32:48 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:32:48 INFO : file7.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:32:48 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:32:48 INFO : file4.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:32:48 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:32:48 INFO : file8.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_compare_all/modfiles" 2026/02/09 04:32:48 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_compare_all-zunonag7" 2026/02/09 04:32:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file10.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file11.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : Local file system at /tmp/042630de/datadir/test_compare_all-zunonag7: Waiting for checks to finish 2026/02/09 04:32:48 DEBUG : Local file system at /tmp/042630de/datadir/test_compare_all-zunonag7: Waiting for transfers to finish 2026/02/09 04:32:48 DEBUG : file1.txt.75b77f44.partial: size = 19 OK 2026/02/09 04:32:48 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:32:48 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2026/02/09 04:32:48 INFO : file1.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file10.txt.c02780cb.partial: size = 19 OK 2026/02/09 04:32:48 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:32:48 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2026/02/09 04:32:48 INFO : file10.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file11.txt.40d797d4.partial: size = 19 OK 2026/02/09 04:32:48 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:32:48 DEBUG : file2.txt.4526d70e.partial: size = 13 OK 2026/02/09 04:32:48 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2026/02/09 04:32:48 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK 2026/02/09 04:32:48 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2026/02/09 04:32:48 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt 2026/02/09 04:32:48 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt 2026/02/09 04:32:48 INFO : file11.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2026/02/09 04:32:48 INFO : file2.txt: Copied (new) 2026/02/09 04:32:48 INFO : file5L.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK 2026/02/09 04:32:48 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2026/02/09 04:32:48 DEBUG : file6.txt.4185169b.partial: size = 19 OK 2026/02/09 04:32:48 DEBUG : file7.txt.c1750184.partial: size = 19 OK 2026/02/09 04:32:48 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:32:48 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:32:48 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2026/02/09 04:32:48 INFO : file5R.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt 2026/02/09 04:32:48 INFO : file7.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2026/02/09 04:32:48 INFO : file6.txt: Copied (new) 2026/02/09 04:32:48 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:48 ERROR : error listing: directory not found 2026/02/09 04:32:48 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/compare_all/path1': Failed to list "": directory not found 2026/02/09 04:32:48 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:48 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/compare_all/path1': Removing directory 2026/02/09 04:32:48 ERROR : Failed to rmdir: directory not found 2026/02/09 04:32:48 DEBUG : Waiting for deletions to finish 2026/02/09 04:32:48 ERROR : error listing: directory not found 2026/02/09 04:32:48 ERROR : Local file system at /tmp/042630de/compare_all/path2: Failed to list "": directory not found 2026/02/09 04:32:48 DEBUG : removing 1 level 0 directories 2026/02/09 04:32:48 INFO : Local file system at /tmp/042630de/compare_all/path2: Removing directory 2026/02/09 04:32:48 ERROR : Failed to rmdir: stat /tmp/042630de/compare_all/path2: no such file or directory 2026/02/09 04:32:48 ERROR : error listing: directory not found 2026/02/09 04:32:48 ERROR : error listing: directory not found 2026/02/09 04:32:48 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_compare_all-jerazur2 2026/02/09 04:32:48 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:32:48 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/compare_all/path1': Waiting for checks to finish 2026/02/09 04:32:48 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/compare_all/path1': Waiting for transfers to finish 2026/02/09 04:32:50 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file3.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file5.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file6.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file7.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:50 ERROR : file8.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:32:53 ERROR : RCLONE_TEST: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 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 file metadata: create meta: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/compare_all Messages: setting up path1 2026/02/09 04:32:53 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/compare_all/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/compare_all Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0) got fstest.go:203: Not found "file6.txt" fstest.go:203: Not found "file1.txt" fstest.go:203: Not found "file2.txt" fstest.go:203: Not found "file3.txt" fstest.go:203: Not found "file5.txt" fstest.go:203: Not found "file7.txt" fstest.go:203: Not found "file8.txt" fstest.go:203: Not found "RCLONE_TEST" fstest.go:203: Not found "file4.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 : 9 Test: TestBisyncRemoteLocal/compare_all Messages: 9 objects not found 2026/02/09 04:33:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:01 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : Local file system at /tmp/042630de/compare_all/path2: Waiting for checks to finish 2026/02/09 04:33:01 DEBUG : Local file system at /tmp/042630de/compare_all/path2: Waiting for transfers to finish 2026/02/09 04:33:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:33:01 INFO : file1.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:33:01 INFO : file3.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:33:01 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:33:01 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:33:01 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:33:01 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:33:01 INFO : file4.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:33:01 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:33:01 INFO : file2.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:33:01 INFO : file5.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:33:01 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:33:01 INFO : file6.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:33:01 INFO : file7.txt: Copied (new) 2026/02/09 04:33:01 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:33:01 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:01 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:33:01 INFO : file8.txt: Copied (new) 2026/02/09 04:33:01 NOTICE: checking path2 Local file system at /tmp/042630de/compare_all/path2 2026/02/09 04:33:01 NOTICE: (01) : test changes compare-all 2026/02/09 04:33:01 NOTICE: (02) : test initial bisync 2026/02/09 04:33:01 NOTICE: (03) : bisync resync compare-all bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/concurrent 2026/02/09 04:33:01 INFO : path1: Making directory 2026/02/09 04:33:01 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/concurrent/path1" 2026/02/09 04:33:01 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/02/09 04:33:02 DEBUG : Creating backend with remote "/tmp/042630de/concurrent/path2" 2026/02/09 04:33:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_concurrent/initial" 2026/02/09 04:33:02 DEBUG : Creating backend with remote "/tmp/042630de/initdir/test_concurrent-paxixoc1" 2026/02/09 04:33:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : Local file system at /tmp/042630de/initdir/test_concurrent-paxixoc1: Waiting for checks to finish 2026/02/09 04:33:02 DEBUG : Local file system at /tmp/042630de/initdir/test_concurrent-paxixoc1: Waiting for transfers to finish 2026/02/09 04:33:02 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:33:02 INFO : file1.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:33:02 INFO : file3.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:33:02 INFO : file2.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:33:02 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:33:02 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:33:02 INFO : file4.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:33:02 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:33:02 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:33:02 INFO : file6.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:33:02 INFO : file8.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:33:02 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:33:02 INFO : file5.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:33:02 INFO : file7.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_concurrent/modfiles" 2026/02/09 04:33:02 DEBUG : Creating backend with remote "/tmp/042630de/datadir/test_concurrent-jojibem8" 2026/02/09 04:33:02 DEBUG : dummy.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file10.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file11.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : dummy.txt.054c2352.partial: size = 0 OK 2026/02/09 04:33:02 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:02 DEBUG : dummy.txt.054c2352.partial: renamed to: dummy.txt 2026/02/09 04:33:02 INFO : dummy.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : Local file system at /tmp/042630de/datadir/test_concurrent-jojibem8: Waiting for checks to finish 2026/02/09 04:33:02 DEBUG : Local file system at /tmp/042630de/datadir/test_concurrent-jojibem8: Waiting for transfers to finish 2026/02/09 04:33:02 DEBUG : file1.txt.0866bb02.partial: size = 19 OK 2026/02/09 04:33:02 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:33:02 DEBUG : file1.txt.0866bb02.partial: renamed to: file1.txt 2026/02/09 04:33:02 INFO : file1.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file10.txt.0bea3133.partial: size = 19 OK 2026/02/09 04:33:02 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:33:02 DEBUG : file2.txt.95cb1da2.partial: size = 13 OK 2026/02/09 04:33:02 DEBUG : file11.txt.62933a56.partial: size = 19 OK 2026/02/09 04:33:02 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2026/02/09 04:33:02 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:33:02 DEBUG : file10.txt.0bea3133.partial: renamed to: file10.txt 2026/02/09 04:33:02 INFO : file10.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file2.txt.95cb1da2.partial: renamed to: file2.txt 2026/02/09 04:33:02 DEBUG : file11.txt.62933a56.partial: renamed to: file11.txt 2026/02/09 04:33:02 INFO : file2.txt: Copied (new) 2026/02/09 04:33:02 INFO : file11.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file5L.txt.0068aeca.partial: size = 39 OK 2026/02/09 04:33:02 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2026/02/09 04:33:02 DEBUG : file5L.txt.0068aeca.partial: renamed to: file5L.txt 2026/02/09 04:33:02 INFO : file5L.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file6.txt.cd788d78.partial: size = 19 OK 2026/02/09 04:33:02 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:33:02 DEBUG : file7.txt.a401861d.partial: size = 19 OK 2026/02/09 04:33:02 DEBUG : file6.txt.cd788d78.partial: renamed to: file6.txt 2026/02/09 04:33:02 INFO : file6.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/02/09 04:33:02 DEBUG : file7.txt.a401861d.partial: renamed to: file7.txt 2026/02/09 04:33:02 INFO : file7.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : file5R.txt.70006d6a.partial: size = 39 OK 2026/02/09 04:33:02 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2026/02/09 04:33:02 DEBUG : file5R.txt.70006d6a.partial: renamed to: file5R.txt 2026/02/09 04:33:02 INFO : file5R.txt: Copied (new) 2026/02/09 04:33:02 DEBUG : Waiting for deletions to finish 2026/02/09 04:33:02 ERROR : error listing: directory not found 2026/02/09 04:33:02 ERROR : Internxt root 'rclone-test-tehuyez8moke/042630de/concurrent/path1': Failed to list "": directory not found 2026/02/09 04:33:02 DEBUG : removing 1 level 0 directories 2026/02/09 04:33:02 INFO : Internxt root 'rclone-test-tehuyez8moke/042630de/concurrent/path1': Removing directory 2026/02/09 04:33:02 ERROR : Failed to rmdir: directory not found 2026/02/09 04:33:02 DEBUG : Waiting for deletions to finish 2026/02/09 04:33:02 ERROR : error listing: directory not found 2026/02/09 04:33:02 ERROR : Local file system at /tmp/042630de/concurrent/path2: Failed to list "": directory not found 2026/02/09 04:33:02 DEBUG : removing 1 level 0 directories 2026/02/09 04:33:02 INFO : Local file system at /tmp/042630de/concurrent/path2: Removing directory 2026/02/09 04:33:02 ERROR : Failed to rmdir: stat /tmp/042630de/concurrent/path2: no such file or directory 2026/02/09 04:33:02 ERROR : error listing: directory not found 2026/02/09 04:33:02 ERROR : error listing: directory not found 2026/02/09 04:33:02 NOTICE: checking initFs Local file system at /tmp/042630de/initdir/test_concurrent-paxixoc1 2026/02/09 04:33:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:02 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/concurrent/path1': Waiting for checks to finish 2026/02/09 04:33:02 DEBUG : Internxt root 'rclone-test-tehuyez8moke/042630de/concurrent/path1': Waiting for transfers to finish 2026/02/09 04:33:03 ERROR : file3.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:03 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:03 ERROR : file2.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:03 ERROR : file5.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:03 ERROR : file6.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:03 ERROR : file4.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:04 ERROR : file8.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:04 ERROR : file7.txt: Failed to copy: failed to create empty file metadata: create meta: error code: 1015 (status 429) 2026/02/09 04:33:04 ERROR : RCLONE_TEST: Failed to copy: failed to create file metadata: create meta: error code: 1015 (status 429) 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 file metadata: create meta: error code: 1015 (status 429) Test: TestBisyncRemoteLocal/concurrent Messages: setting up path1 2026/02/09 04:33:04 NOTICE: checking Path1 Internxt root 'rclone-test-tehuyez8moke/042630de/concurrent/path1' fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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/concurrent Messages: listing wrong, want RCLONE_TEST (109), file1.txt (0), file2.txt (0), file3.txt (0), file4.txt (0), file5.txt (0), file6.txt (0), file7.txt (0), file8.txt (0) got fstest.go:203: Not found "file4.txt" fstest.go:203: Not found "file6.txt" fstest.go:203: Not found "file8.txt" fstest.go:203: Not found "RCLONE_TEST" fstest.go:203: Not found "file1.txt" fstest.go:203: Not found "file2.txt" fstest.go:203: Not found "file3.txt" fstest.go:203: Not found "file5.txt" fstest.go:203: Not found "file7.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 : 9 Test: TestBisyncRemoteLocal/concurrent Messages: 9 objects not found 2026/02/09 04:33:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/02/09 04:33:12 DEBUG : Local file system at /tmp/042630de/concurrent/path2: Waiting for checks to finish 2026/02/09 04:33:12 DEBUG : Local file system at /tmp/042630de/concurrent/path2: Waiting for transfers to finish 2026/02/09 04:33:12 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/02/09 04:33:12 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/02/09 04:33:12 INFO : file2.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/02/09 04:33:12 INFO : file1.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/02/09 04:33:12 INFO : file3.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/02/09 04:33:12 INFO : file4.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/02/09 04:33:12 INFO : file5.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/02/09 04:33:12 INFO : file6.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/02/09 04:33:12 INFO : file7.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/02/09 04:33:12 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/02/09 04:33:12 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/02/09 04:33:12 INFO : file8.txt: Copied (new) 2026/02/09 04:33:12 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/02/09 04:33:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/02/09 04:33:12 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/02/09 04:33:12 INFO : RCLONE_TEST: Copied (new) 2026/02/09 04:33:12 NOTICE: checking path2 Local file system at /tmp/042630de/concurrent/path2 2026/02/09 04:33:12 NOTICE: (01) : test concurrent 2026/02/09 04:33:12 NOTICE: (02) : test initial bisync 2026/02/09 04:33:12 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime === RUN TestBisyncRemoteLocal/createemptysrcdirs 2026/02/09 04:33:12 INFO : path1: Making directory 2026/02/09 04:33:12 DEBUG : Creating backend with remote "TestInternxt:rclone-test-tehuyez8moke/042630de/createemptysrcdirs/path1" 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:646 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:432 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 Error: Received unexpected error: failed to fetch user info: failed to fetch user info: failed to execute refresh token request: Get "https://backend.internxt.com/api/users/cli/refresh": dial tcp 37.59.19.148:443: connect: connection refused Test: TestBisyncRemoteLocal/createemptysrcdirs Messages: parsing remote/subdir TestInternxt:rclone-test-tehuyez8moke/042630de/path1 --- FAIL: TestBisyncRemoteLocal (402.89s) --- FAIL: TestBisyncRemoteLocal/all_changed (31.22s) --- FAIL: TestBisyncRemoteLocal/backupdir (137.91s) --- SKIP: TestBisyncRemoteLocal/basic (5.06s) --- FAIL: TestBisyncRemoteLocal/changes (12.25s) --- FAIL: TestBisyncRemoteLocal/check_access (23.74s) --- FAIL: TestBisyncRemoteLocal/check_access_filters (142.27s) --- FAIL: TestBisyncRemoteLocal/check_filename (12.83s) --- FAIL: TestBisyncRemoteLocal/check_sync (11.35s) --- FAIL: TestBisyncRemoteLocal/compare_all (14.20s) --- FAIL: TestBisyncRemoteLocal/concurrent (11.07s) --- FAIL: TestBisyncRemoteLocal/createemptysrcdirs (0.17s) panic: runtime error: invalid memory address or nil pointer dereference [recovered] panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x28 pc=0xabaffc] goroutine 7430 [running]: testing.tRunner.func1.2({0x24b3040, 0x44c7c10}) /usr/local/go/src/testing/testing.go:1734 +0x21c testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1737 +0x35e panic({0x24b3040?, 0x44c7c10?}) /usr/local/go/src/runtime/panic.go:792 +0x132 github.com/rclone/rclone/cmd/bisync/bilib.FsPath({0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/canonical.go:18 +0x1c github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).makeTempRemote(0xc0005816c0, {0x2edc678, 0xc00044fce0}, {0xc0009023c0, 0x25}, {0x2998042, 0x5}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:647 +0x6bf github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase(0xc0005816c0, {0x2edc678, 0xc00044fce0}, 0xc000581500, {0xc0007151d5, 0x12}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:432 +0x149 github.com/rclone/rclone/cmd/bisync_test.testBisync.func4(0xc000581500) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 +0x234 testing.tRunner(0xc000581500, 0xc000fb49c0) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 33 /usr/local/go/src/testing/testing.go:1851 +0x413 "./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -verbose" - Finished ERROR in 6m43.800373887s (try 1/5): exit status 2: Failed [TestBisyncRemoteLocal/all_changed TestBisyncRemoteLocal/backupdir TestBisyncRemoteLocal/changes TestBisyncRemoteLocal/check_access TestBisyncRemoteLocal/check_access_filters TestBisyncRemoteLocal/check_filename TestBisyncRemoteLocal/check_sync TestBisyncRemoteLocal/compare_all TestBisyncRemoteLocal/concurrent TestBisyncRemoteLocal/createemptysrcdirs]