"./bisync.test -test.v -test.timeout 1h0m0s -remote TestSia: -verbose -test.run '^TestBisyncRemoteLocal$/^changes$'" - Starting (try 2/5)
2024/04/13 04:59:30 DEBUG : Creating backend with remote "TestSia:rclone-test-lukisew3migizap1cemunan9"
2024/04/13 04:59:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/13 04:59:30 DEBUG : Setting type="sia" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_TYPE
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : TestSia: detected overridden config - adding "{-5W69}" suffix to name
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/tmp/rclone3225087459"
=== RUN   TestBisyncRemoteLocal
2024/04/13 04:59:30 DEBUG : Creating backend with remote "TestSia:rclone-test-timuyen7helanup7dotalun5"
2024/04/13 04:59:30 DEBUG : Setting type="sia" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_TYPE
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : TestSia: detected overridden config - adding "{-5W69}" suffix to name
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 remote: TestSia:rclone-test-timuyen7helanup7dotalun5
=== RUN   TestBisyncRemoteLocal/changes
2024/04/13 04:59:30 DEBUG : Creating backend with remote "TestSia:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe"
2024/04/13 04:59:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/13 04:59:30 DEBUG : Setting type="sia" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_TYPE
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : TestSia: detected overridden config - adding "{-5W69}" suffix to name
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : fs cache: renaming cache item "TestSia:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe" to be canonical "TestSia{-5W69}:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe"
2024/04/13 04:59:30 DEBUG : path1: Making directory
2024/04/13 04:59:30 DEBUG : Creating backend with remote "TestSia:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe/changes/path1"
2024/04/13 04:59:30 DEBUG : Setting type="sia" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_TYPE
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : TestSia: detected overridden config - adding "{-5W69}" suffix to name
2024/04/13 04:59:30 DEBUG : Setting api_url="http://127.0.0.1:39980/" for "TestSia" from environment variable RCLONE_CONFIG_TESTSIA_API_URL
2024/04/13 04:59:30 DEBUG : fs cache: renaming cache item "TestSia:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe/changes/path1" to be canonical "TestSia{-5W69}:rclone-test-timuyen7helanup7dotalun5/bs-045930-qe/changes/path1"
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/tmp/bs-045930-qe"
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/tmp/bs-045930-qe/changes/path2"
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/initial"
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/tmp/bs-045930-qe/initdir/test_changes-bemuzit4"
2024/04/13 04:59:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/initdir/test_changes-bemuzit4: Waiting for checks to finish
2024/04/13 04:59:30 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/13 04:59:30 DEBUG : RCLONE_TEST.jewowuk3.partial: renamed to: RCLONE_TEST
2024/04/13 04:59:30 INFO  : RCLONE_TEST: Copied (new)
2024/04/13 04:59:30 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file4.txt.cibokeb6.partial: renamed to: file4.txt
2024/04/13 04:59:30 INFO  : file4.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/initdir/test_changes-bemuzit4: Waiting for transfers to finish
2024/04/13 04:59:30 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file1.txt.xoqowey0.partial: renamed to: file1.txt
2024/04/13 04:59:30 INFO  : file1.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file3.txt.jilanat5.partial: renamed to: file3.txt
2024/04/13 04:59:30 INFO  : file3.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file6.txt.mawedoy3.partial: renamed to: file6.txt
2024/04/13 04:59:30 INFO  : file6.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file7.txt.cezasek0.partial: renamed to: file7.txt
2024/04/13 04:59:30 INFO  : file7.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file8.txt.cuneyag3.partial: renamed to: file8.txt
2024/04/13 04:59:30 INFO  : file8.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file5.txt.hixedek0.partial: renamed to: file5.txt
2024/04/13 04:59:30 INFO  : file5.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file2.txt.payokek9.partial: renamed to: file2.txt
2024/04/13 04:59:30 INFO  : file2.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/modfiles"
2024/04/13 04:59:30 DEBUG : Creating backend with remote "/tmp/bs-045930-qe/datadir/test_changes-qahesop0"
2024/04/13 04:59:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file10.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file11.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/datadir/test_changes-qahesop0: Waiting for checks to finish
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/datadir/test_changes-qahesop0: Waiting for transfers to finish
2024/04/13 04:59:30 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/13 04:59:30 DEBUG : file1.txt.teponuh5.partial: renamed to: file1.txt
2024/04/13 04:59:30 INFO  : file1.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/13 04:59:30 DEBUG : file11.txt.gayigiv9.partial: renamed to: file11.txt
2024/04/13 04:59:30 INFO  : file11.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/13 04:59:30 DEBUG : file10.txt.vafajax6.partial: renamed to: file10.txt
2024/04/13 04:59:30 INFO  : file10.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2024/04/13 04:59:30 DEBUG : file2.txt.tohabox1.partial: renamed to: file2.txt
2024/04/13 04:59:30 INFO  : file2.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2024/04/13 04:59:30 DEBUG : file5R.txt.zohuras7.partial: renamed to: file5R.txt
2024/04/13 04:59:30 INFO  : file5R.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2024/04/13 04:59:30 DEBUG : file5L.txt.fonehod3.partial: renamed to: file5L.txt
2024/04/13 04:59:30 INFO  : file5L.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/13 04:59:30 DEBUG : file7.txt.tuxohim6.partial: renamed to: file7.txt
2024/04/13 04:59:30 INFO  : file7.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/13 04:59:30 DEBUG : file6.txt.mirozor1.partial: renamed to: file6.txt
2024/04/13 04:59:30 INFO  : file6.txt: Copied (new)
2024/04/13 04:59:30 ERROR : : error listing: directory not found
2024/04/13 04:59:30 ERROR : : error listing: directory not found
2024/04/13 04:59:30 checking initFs Local file system at /tmp/bs-045930-qe/initdir/test_changes-bemuzit4
2024/04/13 04:59:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Waiting for checks to finish
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Waiting for transfers to finish
2024/04/13 04:59:30 INFO  : file1.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file2.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file3.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file4.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file5.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file6.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file7.txt: Copied (new)
2024/04/13 04:59:30 INFO  : file8.txt: Copied (new)
2024/04/13 04:59:30 INFO  : RCLONE_TEST: Copied (new)
2024/04/13 04:59:30 checking Path1 Sia http://127.0.0.1:39980
2024/04/13 04:59:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/13 04:59:30 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file1.txt.faqivim1.partial: renamed to: file1.txt
2024/04/13 04:59:30 INFO  : file1.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/changes/path2: Waiting for checks to finish
2024/04/13 04:59:30 DEBUG : Local file system at /tmp/bs-045930-qe/changes/path2: Waiting for transfers to finish
2024/04/13 04:59:30 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/13 04:59:30 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : RCLONE_TEST.jugowek1.partial: renamed to: RCLONE_TEST
2024/04/13 04:59:30 INFO  : RCLONE_TEST: Copied (new)
2024/04/13 04:59:30 DEBUG : file2.txt.lidijuf4.partial: renamed to: file2.txt
2024/04/13 04:59:30 INFO  : file2.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file5.txt.winicog8.partial: renamed to: file5.txt
2024/04/13 04:59:30 INFO  : file5.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file4.txt.cagemur3.partial: renamed to: file4.txt
2024/04/13 04:59:30 INFO  : file4.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file7.txt.toqujel6.partial: renamed to: file7.txt
2024/04/13 04:59:30 INFO  : file7.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file6.txt.nekiqeb3.partial: renamed to: file6.txt
2024/04/13 04:59:30 INFO  : file6.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file8.txt.puwoyoh0.partial: renamed to: file8.txt
2024/04/13 04:59:30 INFO  : file8.txt: Copied (new)
2024/04/13 04:59:30 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/13 04:59:30 DEBUG : file3.txt.gubeyit0.partial: renamed to: file3.txt
2024/04/13 04:59:30 INFO  : file3.txt: Copied (new)
2024/04/13 04:59:30 checking path2 Local file system at /tmp/bs-045930-qe/changes/path2
2024/04/13 04:59:30 (01)  : test changes
2024/04/13 04:59:30 (02)  : test initial bisync
2024/04/13 04:59:30 (03)  : bisync resync
    bisync_test.go:931: skipping test as at least one remote does not support setting modtime
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/RCLONE_TEST"
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file1.txt"
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file2.txt"
2024/04/13 04:59:30 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file3.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file4.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file5.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file6.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file7.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge object "bs-045930-qe/changes/path1/file8.txt"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir "bs-045930-qe/path1"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir "bs-045930-qe/changes/path1"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir "bs-045930-qe/changes"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir "bs-045930-qe"
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir ""
--- PASS: TestBisyncRemoteLocal (0.55s)
    --- SKIP: TestBisyncRemoteLocal/changes (0.30s)
PASS
2024/04/13 04:59:31 ERROR : : error listing: directory not found
2024/04/13 04:59:31 DEBUG : Sia http://127.0.0.1:39980: Purge dir ""
2024/04/13 04:59:31 purge failed to rmdir "": directory not found
2024/04/13 04:59:31 purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestSia: -verbose -test.run '^TestBisyncRemoteLocal$/^changes$'" - Finished OK in 620.189339ms (try 2/5)