"./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^all_changed$|^TestBisyncRemoteLocal$/^resync_modes$'" - Starting (try 5/5)
2026/02/24 04:23:52 DEBUG : Creating backend with remote "TestInternxt:rclone-test-bocijov6vuhu"
2026/02/24 04:23:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/24 04:23:52 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:23:52 DEBUG : Creating backend with remote "/tmp/rclone3794000860"
=== RUN   TestBisyncRemoteLocal
2026/02/24 04:23:52 DEBUG : Creating backend with remote "TestInternxt:rclone-test-vafoyoh1hogu"
2026/02/24 04:23:52 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:23:53 NOTICE: remote: TestInternxt:rclone-test-vafoyoh1hogu
=== RUN   TestBisyncRemoteLocal/resync_modes
2026/02/24 04:23:53 DEBUG : Creating backend with remote "TestInternxt:rclone-test-vafoyoh1hogu/042353fo"
2026/02/24 04:23:53 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:23:53 INFO  : path1: Making directory
2026/02/24 04:23:54 DEBUG : Creating backend with remote "TestInternxt:rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1"
2026/02/24 04:23:54 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/tmp/042353fo"
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/tmp/042353fo/resync_modes/path2"
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/tmp/042353fo/initdir/test_resync_modes-cijeyoh0"
2026/02/24 04:23:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/24 04:23:55 DEBUG : Local file system at /tmp/042353fo/initdir/test_resync_modes-cijeyoh0: Waiting for checks to finish
2026/02/24 04:23:55 DEBUG : Local file system at /tmp/042353fo/initdir/test_resync_modes-cijeyoh0: Waiting for transfers to finish
2026/02/24 04:23:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:23:55 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/24 04:23:55 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/24 04:23:55 INFO  : file1.txt: Copied (new)
2026/02/24 04:23:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:23:55 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/24 04:23:55 INFO  : file2.txt: Copied (new)
2026/02/24 04:23:55 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/24 04:23:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/24 04:23:55 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/24 04:23:55 INFO  : RCLONE_TEST: Copied (new)
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2026/02/24 04:23:55 DEBUG : Creating backend with remote "/tmp/042353fo/datadir/test_resync_modes-gisamid9"
2026/02/24 04:23:55 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:55 DEBUG : Local file system at /tmp/042353fo/datadir/test_resync_modes-gisamid9: Waiting for checks to finish
2026/02/24 04:23:55 DEBUG : Local file system at /tmp/042353fo/datadir/test_resync_modes-gisamid9: Waiting for transfers to finish
2026/02/24 04:23:55 DEBUG : file2.txt.ac4156f2.partial: size = 37 OK
2026/02/24 04:23:55 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2026/02/24 04:23:55 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2026/02/24 04:23:55 INFO  : file2.txt: Copied (new)
2026/02/24 04:23:55 DEBUG : file1R.txt.d467e63a.partial: size = 42 OK
2026/02/24 04:23:55 DEBUG : file1L.txt.684d937d.partial: size = 33 OK
2026/02/24 04:23:55 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2026/02/24 04:23:55 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2026/02/24 04:23:55 INFO  : file1L.txt: Copied (new)
2026/02/24 04:23:55 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2026/02/24 04:23:55 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2026/02/24 04:23:55 INFO  : file1R.txt: Copied (new)
2026/02/24 04:23:55 DEBUG : Waiting for deletions to finish
2026/02/24 04:23:55 ERROR : error listing: directory not found
2026/02/24 04:23:56 ERROR : Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1': Failed to list "": directory not found
2026/02/24 04:23:56 DEBUG : removing 1 level 0 directories
2026/02/24 04:23:56 INFO  : Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1': Removing directory
2026/02/24 04:23:56 ERROR : Failed to rmdir: directory not found
2026/02/24 04:23:56 DEBUG : Waiting for deletions to finish
2026/02/24 04:23:56 ERROR : error listing: directory not found
2026/02/24 04:23:56 ERROR : Local file system at /tmp/042353fo/resync_modes/path2: Failed to list "": directory not found
2026/02/24 04:23:56 DEBUG : removing 1 level 0 directories
2026/02/24 04:23:56 INFO  : Local file system at /tmp/042353fo/resync_modes/path2: Removing directory
2026/02/24 04:23:56 ERROR : Failed to rmdir: stat /tmp/042353fo/resync_modes/path2: no such file or directory
2026/02/24 04:23:56 ERROR : error listing: directory not found
2026/02/24 04:23:56 ERROR : error listing: directory not found
2026/02/24 04:23:56 NOTICE: checking initFs Local file system at /tmp/042353fo/initdir/test_resync_modes-cijeyoh0
2026/02/24 04:23:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:23:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/24 04:23:56 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1': Waiting for checks to finish
2026/02/24 04:23:56 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1': Waiting for transfers to finish
2026/02/24 04:23:57 ERROR : file1.txt: Failed to copy: failed to create empty file metadata: create meta: Folder not found (status 404)
2026/02/24 04:23:57 DEBUG : file2.txt: size = 0 OK
2026/02/24 04:23:57 INFO  : file2.txt: Copied (new)
2026/02/24 04:23:57 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/24 04:23:57 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/resync_modes
        	Messages:   	setting up path1
2026/02/24 04:23:57 NOTICE: checking Path1 Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1'
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/5
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/5
    fstest.go:298: Sleeping for 8s for list eventual consistency: 4/5
    fstest.go:298: Sleeping for 16s for list eventual consistency: 5/5
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Should be true
        	Test:       	TestBisyncRemoteLocal/resync_modes
        	Messages:   	listing wrong, want
        	            	  RCLONE_TEST (109), file1.txt (0), file2.txt (0) got
        	            	  RCLONE_TEST (109), file2.txt (0)
    fstest.go:203: Not found "file1.txt"
    fstest.go:206: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:508
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestBisyncRemoteLocal/resync_modes
        	Messages:   	1 objects not found
2026/02/24 04:24:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:24:29 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:29 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:29 DEBUG : Local file system at /tmp/042353fo/resync_modes/path2: Waiting for checks to finish
2026/02/24 04:24:29 DEBUG : Local file system at /tmp/042353fo/resync_modes/path2: Waiting for transfers to finish
2026/02/24 04:24:29 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/02/24 04:24:29 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:29 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/24 04:24:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:29 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/02/24 04:24:29 INFO  : file2.txt: Copied (new)
2026/02/24 04:24:29 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/24 04:24:29 INFO  : file1.txt: Copied (new)
2026/02/24 04:24:29 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/24 04:24:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/24 04:24:29 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/24 04:24:29 INFO  : RCLONE_TEST: Copied (new)
2026/02/24 04:24:29 NOTICE: checking path2 Local file system at /tmp/042353fo/resync_modes/path2
2026/02/24 04:24:29 NOTICE: (01)  : test resync-mode
2026/02/24 04:24:29 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2026/02/24 04:24:29 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/042353fo/datadir/test_resync_modes-gisamid9/ file1R.txt
2026/02/24 04:24:29 DEBUG : Creating backend with remote "/tmp/042353fo/datadir/test_resync_modes-gisamid9/"
2026/02/24 04:24:29 DEBUG : fs cache: renaming cache item "/tmp/042353fo/datadir/test_resync_modes-gisamid9/" to be canonical "/tmp/042353fo/datadir/test_resync_modes-gisamid9"
2026/02/24 04:24:29 DEBUG : file1R.txt: Set modification time 2001-01-02
2026/02/24 04:24:29 NOTICE: (04)  : copy-as /tmp/042353fo/datadir/test_resync_modes-gisamid9/file1R.txt /tmp/042353fo/resync_modes/path2/ file1.txt
2026/02/24 04:24:29 DEBUG : copyFile "/tmp/042353fo/datadir/test_resync_modes-gisamid9/file1R.txt" to "/tmp/042353fo/resync_modes/path2/" as "file1.txt"
2026/02/24 04:24:29 DEBUG : Creating backend with remote "/tmp/042353fo/datadir/test_resync_modes-gisamid9/file1R.txt"
2026/02/24 04:24:29 DEBUG : Creating backend with remote "/tmp/042353fo/resync_modes/path2/"
2026/02/24 04:24:29 DEBUG : operations.CopyFile "file1R.txt" to "Local file system at /tmp/042353fo/resync_modes/path2" as "file1.txt"
2026/02/24 04:24:29 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/042353fo/datadir/test_resync_modes-gisamid9)
2026/02/24 04:24:29 DEBUG : file1.txt: size = 0 (Local file system at /tmp/042353fo/resync_modes/path2)
2026/02/24 04:24:29 DEBUG : file1R.txt: Sizes differ
2026/02/24 04:24:29 DEBUG : file1.txt.91f01f3f.partial: size = 42 OK
2026/02/24 04:24:29 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2026/02/24 04:24:29 DEBUG : file1.txt.91f01f3f.partial: renamed to: file1.txt
2026/02/24 04:24:29 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2026/02/24 04:24:29 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/042353fo/datadir/test_resync_modes-gisamid9/ file1L.txt
2026/02/24 04:24:29 DEBUG : fs cache: switching user supplied name "/tmp/042353fo/datadir/test_resync_modes-gisamid9/" for canonical name "/tmp/042353fo/datadir/test_resync_modes-gisamid9"
2026/02/24 04:24:29 DEBUG : file1L.txt: Set modification time 2001-03-04
2026/02/24 04:24:29 NOTICE: (06)  : copy-as /tmp/042353fo/datadir/test_resync_modes-gisamid9/file1L.txt TestInternxt:rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1/ file1.txt
2026/02/24 04:24:29 DEBUG : copyFile "/tmp/042353fo/datadir/test_resync_modes-gisamid9/file1L.txt" to "TestInternxt:rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1/" as "file1.txt"
2026/02/24 04:24:29 DEBUG : Creating backend with remote "/tmp/042353fo/datadir/test_resync_modes-gisamid9/file1L.txt"
2026/02/24 04:24:29 DEBUG : Creating backend with remote "TestInternxt:rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1/"
2026/02/24 04:24:30 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:24:30 DEBUG : operations.CopyFile "file1L.txt" to "Internxt root 'rclone-test-vafoyoh1hogu/042353fo/resync_modes/path1'" as "file1.txt"
2026/02/24 04:24:30 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:32 DEBUG : file1.txt: size = 33 OK
2026/02/24 04:24:32 INFO  : file1L.txt: Copied (new) to: file1.txt
2026/02/24 04:24:32 NOTICE: (07)  : test bisync run with --resync-mode=newer
2026/02/24 04:24:32 NOTICE: (08)  : bisync resync resync-mode=newer
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
2026/02/24 04:24:34 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge object "042353fo/resync_modes/path1/RCLONE_TEST"
2026/02/24 04:24:34 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge object "042353fo/resync_modes/path1/file1.txt"
2026/02/24 04:24:34 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge object "042353fo/resync_modes/path1/file2.txt"
2026/02/24 04:24:34 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge dir "042353fo/resync_modes/path1"
2026/02/24 04:24:35 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge dir "042353fo/resync_modes"
2026/02/24 04:24:35 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge dir "042353fo/path1"
2026/02/24 04:24:35 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge dir "042353fo"
2026/02/24 04:24:36 DEBUG : Internxt root 'rclone-test-vafoyoh1hogu': Purge dir ""
2026/02/24 04:24:36 NOTICE: purge failed to rmdir "": directory not empty
--- FAIL: TestBisyncRemoteLocal (43.50s)
    --- FAIL: TestBisyncRemoteLocal/resync_modes (39.32s)
=== RUN   TestBisyncLocalRemote
2026/02/24 04:24:36 DEBUG : Creating backend with remote "TestInternxt:rclone-test-cubedil3jutu"
2026/02/24 04:24:36 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:24:36 NOTICE: remote: TestInternxt:rclone-test-cubedil3jutu
=== RUN   TestBisyncLocalRemote/all_changed
2026/02/24 04:24:36 DEBUG : Creating backend with remote "/tmp/042436wu"
2026/02/24 04:24:36 DEBUG : Creating backend with remote "/tmp/042436wu/all_changed/path1"
2026/02/24 04:24:36 DEBUG : Creating backend with remote "TestInternxt:rclone-test-cubedil3jutu/042436wu"
2026/02/24 04:24:37 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:24:37 INFO  : path2: Making directory
2026/02/24 04:24:38 DEBUG : Creating backend with remote "TestInternxt:rclone-test-cubedil3jutu/042436wu/all_changed/path2"
2026/02/24 04:24:38 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2
2026/02/24 04:24:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial"
2026/02/24 04:24:39 DEBUG : Creating backend with remote "/tmp/042436wu/initdir/test_all_changed-qoyiguq1"
2026/02/24 04:24:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : subdir: Making directory with metadata
2026/02/24 04:24:39 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/02/24 04:24:39 DEBUG : Added delayed dir = "subdir", newDst=subdir
2026/02/24 04:24:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:39 DEBUG : Local file system at /tmp/042436wu/initdir/test_all_changed-qoyiguq1: Waiting for checks to finish
2026/02/24 04:24:39 DEBUG : Local file system at /tmp/042436wu/initdir/test_all_changed-qoyiguq1: Waiting for transfers to finish
2026/02/24 04:24:39 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2026/02/24 04:24:39 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2026/02/24 04:24:39 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2026/02/24 04:24:39 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2026/02/24 04:24:39 INFO  : file1.copy1.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2026/02/24 04:24:39 INFO  : file1.copy3.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2026/02/24 04:24:39 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2026/02/24 04:24:39 INFO  : file1.copy2.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/24 04:24:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/24 04:24:39 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/24 04:24:39 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/24 04:24:39 INFO  : RCLONE_TEST: Copied (new)
2026/02/24 04:24:39 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2026/02/24 04:24:39 INFO  : file1.copy4.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/24 04:24:39 INFO  : file1.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2026/02/24 04:24:39 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2026/02/24 04:24:39 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2026/02/24 04:24:39 INFO  : subdir/file20.txt: Copied (new)
2026/02/24 04:24:39 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:39 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2026/02/24 04:24:39 INFO  : file1.copy5.txt: Copied (new)
2026/02/24 04:24:39 INFO  : subdir: Set directory modification time (using SetModTime)
2026/02/24 04:24:39 DEBUG : Waiting for deletions to finish
2026/02/24 04:24:39 ERROR : error listing: directory not found
2026/02/24 04:24:39 ERROR : Local file system at /tmp/042436wu/all_changed/path1: Failed to list "": directory not found
2026/02/24 04:24:39 DEBUG : removing 1 level 0 directories
2026/02/24 04:24:39 INFO  : Local file system at /tmp/042436wu/all_changed/path1: Removing directory
2026/02/24 04:24:39 ERROR : Failed to rmdir: stat /tmp/042436wu/all_changed/path1: no such file or directory
2026/02/24 04:24:39 DEBUG : Waiting for deletions to finish
2026/02/24 04:24:39 ERROR : error listing: directory not found
2026/02/24 04:24:39 ERROR : Internxt root 'rclone-test-cubedil3jutu/042436wu/all_changed/path2': Failed to list "": directory not found
2026/02/24 04:24:39 DEBUG : removing 1 level 0 directories
2026/02/24 04:24:39 INFO  : Internxt root 'rclone-test-cubedil3jutu/042436wu/all_changed/path2': Removing directory
2026/02/24 04:24:39 ERROR : Failed to rmdir: directory not found
2026/02/24 04:24:39 ERROR : error listing: directory not found
2026/02/24 04:24:39 ERROR : error listing: directory not found
2026/02/24 04:24:39 NOTICE: checking initFs Local file system at /tmp/042436wu/initdir/test_all_changed-qoyiguq1
2026/02/24 04:24:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : subdir: Making directory with metadata
2026/02/24 04:24:40 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/02/24 04:24:40 DEBUG : Added delayed dir = "subdir", newDst=subdir
2026/02/24 04:24:40 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : Local file system at /tmp/042436wu/all_changed/path1: Waiting for checks to finish
2026/02/24 04:24:40 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : Local file system at /tmp/042436wu/all_changed/path1: Waiting for transfers to finish
2026/02/24 04:24:40 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2026/02/24 04:24:40 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 INFO  : file1.copy2.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2026/02/24 04:24:40 INFO  : file1.copy3.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2026/02/24 04:24:40 INFO  : file1.copy1.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/02/24 04:24:40 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/02/24 04:24:40 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/02/24 04:24:40 INFO  : RCLONE_TEST: Copied (new)
2026/02/24 04:24:40 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2026/02/24 04:24:40 INFO  : file1.copy4.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2026/02/24 04:24:40 INFO  : file1.copy5.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/02/24 04:24:40 INFO  : file1.txt: Copied (new)
2026/02/24 04:24:40 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2026/02/24 04:24:40 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/02/24 04:24:40 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2026/02/24 04:24:40 INFO  : subdir/file20.txt: Copied (new)
2026/02/24 04:24:40 INFO  : subdir: Set directory modification time (using SetModTime)
2026/02/24 04:24:40 NOTICE: checking Path1 Local file system at /tmp/042436wu/all_changed/path1
2026/02/24 04:24:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 INFO  : subdir: Making directory
2026/02/24 04:24:40 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/02/24 04:24:40 DEBUG : Internxt root 'rclone-test-cubedil3jutu/042436wu/all_changed/path2': Waiting for checks to finish
2026/02/24 04:24:40 DEBUG : Internxt root 'rclone-test-cubedil3jutu/042436wu/all_changed/path2': Waiting for transfers to finish
2026/02/24 04:24:41 DEBUG : file1.copy2.txt: size = 0 OK
2026/02/24 04:24:41 DEBUG : file1.copy1.txt: size = 0 OK
2026/02/24 04:24:41 INFO  : file1.copy2.txt: Copied (new)
2026/02/24 04:24:41 INFO  : file1.copy1.txt: Copied (new)
2026/02/24 04:24:41 DEBUG : file1.copy3.txt: size = 0 OK
2026/02/24 04:24:41 INFO  : file1.copy3.txt: Copied (new)
2026/02/24 04:24:41 DEBUG : file1.copy4.txt: size = 0 OK
2026/02/24 04:24:41 INFO  : file1.copy4.txt: Copied (new)
2026/02/24 04:24:41 DEBUG : RCLONE_TEST: size = 109 OK
2026/02/24 04:24:41 INFO  : RCLONE_TEST: Copied (new)
2026/02/24 04:24:41 DEBUG : file1.copy5.txt: size = 0 OK
2026/02/24 04:24:41 INFO  : file1.copy5.txt: Copied (new)
2026/02/24 04:24:42 DEBUG : subdir/file20.txt: size = 0 OK
2026/02/24 04:24:42 INFO  : subdir/file20.txt: Copied (new)
2026/02/24 04:24:43 DEBUG : file1.txt: size = 0 OK
2026/02/24 04:24:43 INFO  : file1.txt: Copied (new)
2026/02/24 04:24:43 NOTICE: checking path2 Internxt root 'rclone-test-cubedil3jutu/042436wu/all_changed/path2'
2026/02/24 04:24:43 NOTICE: (01)  : test all-changed
2026/02/24 04:24:43 NOTICE: (02)  : test initial bisync
2026/02/24 04:24:43 NOTICE: (03)  : bisync resync
    bisync_test.go:1015: skipping test as at least one remote does not support setting modtime
2026/02/24 04:24:44 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/RCLONE_TEST"
2026/02/24 04:24:45 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.copy1.txt"
2026/02/24 04:24:45 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.copy2.txt"
2026/02/24 04:24:45 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.copy3.txt"
2026/02/24 04:24:45 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.copy4.txt"
2026/02/24 04:24:46 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.copy5.txt"
2026/02/24 04:24:46 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/file1.txt"
2026/02/24 04:24:46 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge object "042436wu/all_changed/path2/subdir/file20.txt"
2026/02/24 04:24:46 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir "042436wu/path2"
2026/02/24 04:24:47 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir "042436wu/all_changed/path2/subdir"
2026/02/24 04:24:47 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir "042436wu/all_changed/path2"
2026/02/24 04:24:47 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir "042436wu/all_changed"
2026/02/24 04:24:47 NOTICE: purge failed to rmdir "042436wu/all_changed": directory not empty
2026/02/24 04:24:47 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir "042436wu"
2026/02/24 04:24:48 NOTICE: purge failed to rmdir "042436wu": directory not empty
2026/02/24 04:24:48 DEBUG : Internxt root 'rclone-test-cubedil3jutu': Purge dir ""
2026/02/24 04:24:48 NOTICE: purge failed to rmdir "": directory not empty
--- PASS: TestBisyncLocalRemote (11.85s)
    --- SKIP: TestBisyncLocalRemote/all_changed (6.77s)
FAIL
2026/02/24 04:24:48 ERROR : error listing: directory not found
2026/02/24 04:24:48 DEBUG : Internxt root 'rclone-test-bocijov6vuhu': Purge dir ""
2026/02/24 04:24:48 NOTICE: purge failed to rmdir "": directory not found
2026/02/24 04:24:48 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^all_changed$|^TestBisyncRemoteLocal$/^resync_modes$'" - Finished ERROR in 56.521972977s (try 5/5): exit status 1: Failed [TestBisyncRemoteLocal/resync_modes]