"./bisync.test -test.v -test.timeout 1h0m0s -remote TestSMBKerberos:rclone -verbose" - Starting (try 3/5) 2025/11/03 04:00:39 DEBUG : Creating backend with remote "TestSMBKerberos:rclone/rclone-test-vixumuv2debi" 2025/11/03 04:00:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/03 04:00:39 DEBUG : Setting type="smb" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_TYPE 2025/11/03 04:00:39 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:39 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:39 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:39 DEBUG : TestSMBKerberos: detected overridden config - adding "{05jGr}" suffix to name 2025/11/03 04:00:39 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:39 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:39 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:39 DEBUG : pacer: low level retry 1/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 2/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 3/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 4/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 5/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 6/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/03 04:00:39 DEBUG : pacer: low level retry 7/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:39 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/03 04:00:40 DEBUG : pacer: low level retry 8/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:40 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/11/03 04:00:41 DEBUG : pacer: low level retry 9/10 (error couldn't connect SMB: connection error: write tcp 127.0.0.1:44956->127.0.0.1:28633: write: broken pipe) 2025/11/03 04:00:43 DEBUG : pacer: Reducing sleep to 1.5s 2025/11/03 04:00:43 DEBUG : Creating backend with remote "/tmp/rclone4257480418" === RUN TestBisyncRemoteLocal 2025/11/03 04:00:43 DEBUG : Creating backend with remote "TestSMBKerberos:rclone/rclone-test-gawinaw7veda" 2025/11/03 04:00:43 DEBUG : Setting type="smb" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_TYPE 2025/11/03 04:00:43 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:43 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:43 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:43 DEBUG : TestSMBKerberos: detected overridden config - adding "{05jGr}" suffix to name 2025/11/03 04:00:43 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:43 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:43 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:43 NOTICE: remote: TestSMBKerberos:rclone/rclone-test-gawinaw7veda === RUN TestBisyncRemoteLocal/all_changed 2025/11/03 04:00:43 DEBUG : Creating backend with remote "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi" 2025/11/03 04:00:43 DEBUG : Setting type="smb" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_TYPE 2025/11/03 04:00:43 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:43 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:43 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:43 DEBUG : TestSMBKerberos: detected overridden config - adding "{05jGr}" suffix to name 2025/11/03 04:00:43 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:43 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:43 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:44 DEBUG : fs cache: renaming cache item "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi" to be canonical "TestSMBKerberos{05jGr}:rclone/rclone-test-gawinaw7veda/040043gi" 2025/11/03 04:00:44 INFO : path1: Making directory 2025/11/03 04:00:44 DEBUG : Creating backend with remote "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1" 2025/11/03 04:00:44 DEBUG : Setting type="smb" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_TYPE 2025/11/03 04:00:44 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:44 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:44 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:44 DEBUG : TestSMBKerberos: detected overridden config - adding "{05jGr}" suffix to name 2025/11/03 04:00:44 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:44 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:44 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:44 DEBUG : fs cache: renaming cache item "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1" to be canonical "TestSMBKerberos{05jGr}:rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1" 2025/11/03 04:00:44 DEBUG : Creating backend with remote "/tmp/040043gi" 2025/11/03 04:00:44 DEBUG : Creating backend with remote "/tmp/040043gi/all_changed/path2" 2025/11/03 04:00:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial" 2025/11/03 04:00:44 DEBUG : Creating backend with remote "/tmp/040043gi/initdir/test_all_changed-juyujeg3" 2025/11/03 04:00:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : subdir: Making directory with metadata 2025/11/03 04:00:44 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/11/03 04:00:44 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/11/03 04:00:44 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : Local file system at /tmp/040043gi/initdir/test_all_changed-juyujeg3: Waiting for checks to finish 2025/11/03 04:00:44 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : Local file system at /tmp/040043gi/initdir/test_all_changed-juyujeg3: Waiting for transfers to finish 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/11/03 04:00:44 INFO : file1.copy3.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2025/11/03 04:00:44 INFO : file1.copy1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/11/03 04:00:44 INFO : file1.copy4.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/11/03 04:00:44 INFO : file1.copy2.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/11/03 04:00:44 INFO : file1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/11/03 04:00:44 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/11/03 04:00:44 INFO : file1.copy5.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/11/03 04:00:44 INFO : subdir/file20.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/11/03 04:00:44 INFO : RCLONE_TEST: Copied (new) 2025/11/03 04:00:44 INFO : subdir: Set directory modification time (using SetModTime) 2025/11/03 04:00:44 DEBUG : Waiting for deletions to finish 2025/11/03 04:00:44 ERROR : error listing: directory not found 2025/11/03 04:00:44 ERROR : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1: Failed to list "": directory not found 2025/11/03 04:00:44 DEBUG : removing 1 level 0 directories 2025/11/03 04:00:44 INFO : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1: Removing directory 2025/11/03 04:00:44 ERROR : Failed to rmdir: remove rclone-test-gawinaw7veda\040043gi\all_changed\path1: file does not exist 2025/11/03 04:00:44 DEBUG : Waiting for deletions to finish 2025/11/03 04:00:44 ERROR : error listing: directory not found 2025/11/03 04:00:44 ERROR : Local file system at /tmp/040043gi/all_changed/path2: Failed to list "": directory not found 2025/11/03 04:00:44 DEBUG : removing 1 level 0 directories 2025/11/03 04:00:44 INFO : Local file system at /tmp/040043gi/all_changed/path2: Removing directory 2025/11/03 04:00:44 ERROR : Failed to rmdir: stat /tmp/040043gi/all_changed/path2: no such file or directory 2025/11/03 04:00:44 ERROR : error listing: directory not found 2025/11/03 04:00:44 ERROR : error listing: directory not found 2025/11/03 04:00:44 NOTICE: checking initFs Local file system at /tmp/040043gi/initdir/test_all_changed-juyujeg3 2025/11/03 04:00:44 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/03 04:00:44 INFO : subdir: Making directory 2025/11/03 04:00:44 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1: Waiting for checks to finish 2025/11/03 04:00:44 DEBUG : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1: Waiting for transfers to finish 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/11/03 04:00:44 INFO : file1.copy2.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/11/03 04:00:44 INFO : file1.copy4.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/11/03 04:00:44 INFO : file1.copy5.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/11/03 04:00:44 INFO : file1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/11/03 04:00:44 INFO : RCLONE_TEST: Copied (new) 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/11/03 04:00:44 INFO : subdir/file20.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/11/03 04:00:44 INFO : file1.copy1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/11/03 04:00:44 INFO : file1.copy3.txt: Copied (new) 2025/11/03 04:00:44 NOTICE: checking Path1 smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1 2025/11/03 04:00:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : subdir: Making directory with metadata 2025/11/03 04:00:44 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/11/03 04:00:44 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/11/03 04:00:44 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : Local file system at /tmp/040043gi/all_changed/path2: Waiting for checks to finish 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : Local file system at /tmp/040043gi/all_changed/path2: Waiting for transfers to finish 2025/11/03 04:00:44 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/11/03 04:00:44 INFO : file1.copy1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/11/03 04:00:44 INFO : file1.copy2.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/11/03 04:00:44 INFO : file1.copy4.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/11/03 04:00:44 INFO : file1.copy5.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/11/03 04:00:44 INFO : file1.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2025/11/03 04:00:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2025/11/03 04:00:44 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/11/03 04:00:44 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/11/03 04:00:44 INFO : RCLONE_TEST: Copied (new) 2025/11/03 04:00:44 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/11/03 04:00:44 INFO : subdir/file20.txt: Copied (new) 2025/11/03 04:00:44 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/11/03 04:00:44 INFO : file1.copy3.txt: Copied (new) 2025/11/03 04:00:44 INFO : subdir: Set directory modification time (using SetModTime) 2025/11/03 04:00:44 NOTICE: checking path2 Local file system at /tmp/040043gi/all_changed/path2 2025/11/03 04:00:44 NOTICE: (01) : test all-changed 2025/11/03 04:00:44 NOTICE: (02) : test initial bisync 2025/11/03 04:00:44 NOTICE: (03) : bisync resync 2025/11/03 04:00:46 DEBUG : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi/all_changed/path1: Connection failed, closing: connection error: EOF bisync_test.go:1042: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1042 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1045 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542 /usr/local/go/src/strings/iter.go:61 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 Error: Received unexpected error: remove rclone-test-gawinaw7veda\040043gi\all_changed\path1\modtime_write_test: connection error: EOF Test: TestBisyncRemoteLocal/all_changed === RUN TestBisyncRemoteLocal/backupdir 2025/11/03 04:00:46 DEBUG : fs cache: switching user supplied name "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi" for canonical name "TestSMBKerberos{05jGr}:rclone/rclone-test-gawinaw7veda/040043gi" 2025/11/03 04:00:46 INFO : path1: Making directory 2025/11/03 04:00:46 DEBUG : smb://rclone@localhost:28633/rclone/rclone-test-gawinaw7veda/040043gi: Connection failed, closing: connection error: EOF 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: mkdir rclone-test-gawinaw7veda: connection error: EOF Test: TestBisyncRemoteLocal/backupdir Messages: Mkdir path1 2025/11/03 04:00:46 DEBUG : Creating backend with remote "TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi/backupdir/path1" 2025/11/03 04:00:46 DEBUG : Setting type="smb" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_TYPE 2025/11/03 04:00:46 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:46 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:46 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:46 DEBUG : TestSMBKerberos: detected overridden config - adding "{05jGr}" suffix to name 2025/11/03 04:00:46 DEBUG : Setting host="localhost" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_HOST 2025/11/03 04:00:46 DEBUG : Setting port="28633" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_PORT 2025/11/03 04:00:46 DEBUG : Setting use_kerberos="true" for "TestSMBKerberos" from environment variable RCLONE_CONFIG_TESTSMBKERBEROS_USE_KERBEROS 2025/11/03 04:00:46 DEBUG : pacer: low level retry 1/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 2/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 3/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 4/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 5/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 6/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/03 04:00:46 DEBUG : pacer: low level retry 7/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:46 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/03 04:00:47 DEBUG : pacer: low level retry 8/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:47 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/11/03 04:00:48 DEBUG : pacer: low level retry 9/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 2025/11/03 04:00:50 DEBUG : pacer: low level retry 10/10 (error couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused) 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: couldn't connect SMB: dial tcp 127.0.0.1:28633: connect: connection refused Test: TestBisyncRemoteLocal/backupdir Messages: parsing remote/subdir TestSMBKerberos:rclone/rclone-test-gawinaw7veda/040043gi/path1 --- FAIL: TestBisyncRemoteLocal (7.00s) --- FAIL: TestBisyncRemoteLocal/all_changed (2.37s) --- FAIL: TestBisyncRemoteLocal/backupdir (4.56s) 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=0xabb91c] goroutine 638 [running]: testing.tRunner.func1.2({0x23c2940, 0x41932d0}) /usr/local/go/src/testing/testing.go:1734 +0x21c testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1737 +0x35e panic({0x23c2940?, 0x41932d0?}) /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(0xc000003c00, {0x2d5e7a8, 0xc000320a20}, {0xc0008794a0, 0x2f}, {0x2887579, 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(0xc000003c00, {0x2d5e7a8, 0xc000320a20}, 0xc000002000, {0xc00035cf05, 0x9}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:432 +0x149 github.com/rclone/rclone/cmd/bisync_test.testBisync.func4(0xc000002000) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 +0x234 testing.tRunner(0xc000002000, 0xc000683200) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 64 /usr/local/go/src/testing/testing.go:1851 +0x413 "./bisync.test -test.v -test.timeout 1h0m0s -remote TestSMBKerberos:rclone -verbose" - Finished ERROR in 11.79534575s (try 3/5): exit status 2: Failed [TestBisyncRemoteLocal/all_changed TestBisyncRemoteLocal/backupdir]