"./vfs.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^(TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestVFSStat)$'" - Starting (try 2/5)
2024/12/19 02:46:48 DEBUG : Creating backend with remote "TestStorj:rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:46:48 DEBUG : FS sj://rclone-test-casajif1xeyo: connecting...
2024/12/19 02:46:48 DEBUG : FS sj://rclone-test-casajif1xeyo: connected: <nil>
2024/12/19 02:46:48 DEBUG : Creating backend with remote "/tmp/rclone3325143745"
=== RUN   TestRWFileHandleWriteAt
    run.go:180: Remote "FS sj://rclone-test-casajif1xeyo", Local "Local file system at /tmp/rclone3325143745", Modify Window "1ns"
2024/12/19 02:46:48 INFO  : FS sj://rclone-test-casajif1xeyo: poll-interval is not supported by this remote
2024/12/19 02:46:48 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone"
2024/12/19 02:46:48 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/12/19 02:46:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/12/19 02:46:48 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:48 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2024/12/19 02:46:48 DEBUG : FS sj://rclone-test-casajif1xeyo: ls ./
2024/12/19 02:46:48 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:48 DEBUG : FS sj://rclone-test-casajif1xeyo: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/12/19 02:46:48 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/12/19 02:46:49 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2024/12/19 02:46:49 DEBUG : file1: newRWFileHandle: 
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): openPending: 
2024/12/19 02:46:49 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct)
2024/12/19 02:46:49 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): >openPending: err=<nil>
2024/12/19 02:46:49 DEBUG : file1: >newRWFileHandle: err=<nil>
2024/12/19 02:46:49 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:49 DEBUG : file1: >Open: fd=file1 (rw), err=<nil>
2024/12/19 02:46:49 DEBUG : file1: >OpenFile: fd=file1 (rw), err=<nil>
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): _writeAt: size=7, off=0
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): >_writeAt: n=7, err=<nil>
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): _writeAt: size=6, off=5
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): >_writeAt: n=6, err=<nil>
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): close: 
2024/12/19 02:46:49 DEBUG : file1: vfs cache: setting modification time to 2024-12-19 02:46:49.060385211 +0000 UTC m=+0.134414689
2024/12/19 02:46:49 INFO  : file1: vfs cache: queuing for upload in 100ms
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): >close: err=<nil>
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): _writeAt: size=5, off=0
2024/12/19 02:46:49 DEBUG : file1(0xc0001302c0): >_writeAt: n=0, err=file already closed
2024/12/19 02:46:49 DEBUG : WaitForWriters: timeout=30s
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms
2024/12/19 02:46:49 DEBUG : file1: vfs cache: starting upload
2024/12/19 02:46:49 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file1 # [HashesOption([])] 11
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms
2024/12/19 02:46:49 DEBUG : file1: Received error: bucket was not available, now created, the upload must be retried - low level retry 0/10
2024/12/19 02:46:49 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file1 # [HashesOption([])] 11
2024/12/19 02:46:49 DEBUG : FS sj://rclone-test-casajif1xeyo: uploading too fast - sleeping for 1 second: uplink: too many requests
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2024/12/19 02:46:49 DEBUG : : Looking for writers
2024/12/19 02:46:49 DEBUG : file1: reading active writers
2024/12/19 02:46:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms
2024/12/19 02:46:50 DEBUG : : Looking for writers
2024/12/19 02:46:50 DEBUG : file1: reading active writers
2024/12/19 02:46:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2024/12/19 02:46:50 DEBUG : file1: Received error: uplink: too many requests - low level retry 1/10
2024/12/19 02:46:50 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file1 # [HashesOption([])] 11
2024/12/19 02:46:50 INFO  : file1: Copied (new)
2024/12/19 02:46:50 DEBUG : file1: vfs cache: fingerprint now "11,2024-12-19 02:46:49.060385211 +0000 UTC"
2024/12/19 02:46:50 DEBUG : file1: vfs cache: writeback object to VFS layer
2024/12/19 02:46:50 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:50 INFO  : file1: vfs cache: upload succeeded try #1
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : >WaitForWriters: 
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:51 DEBUG : WaitForWriters: timeout=30s
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : >WaitForWriters: 
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:51 DEBUG : vfs cache: cleaner exiting
2024/12/19 02:46:51 DEBUG : file1: rm sj://rclone-test-casajif1xeyo/file1
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
--- PASS: TestRWFileHandleWriteAt (2.57s)
=== RUN   TestRWFileHandleWriteNoWrite
    run.go:180: Remote "FS sj://rclone-test-casajif1xeyo", Local "Local file system at /tmp/rclone3325143745", Modify Window "1ns"
2024/12/19 02:46:51 INFO  : FS sj://rclone-test-casajif1xeyo: poll-interval is not supported by this remote
2024/12/19 02:46:51 DEBUG : vfs cache: root is "/home/rclone/.cache/rclone"
2024/12/19 02:46:51 DEBUG : vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/12/19 02:46:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/12/19 02:46:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestStorj/rclone-test-casajif1xeyo"
2024/12/19 02:46:51 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: ls ./
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/12/19 02:46:51 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/12/19 02:46:51 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2024/12/19 02:46:51 DEBUG : file1: newRWFileHandle: 
2024/12/19 02:46:51 DEBUG : file1(0xc0001314c0): openPending: 
2024/12/19 02:46:51 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct)
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:51 DEBUG : file1(0xc0001314c0): >openPending: err=<nil>
2024/12/19 02:46:51 DEBUG : file1: >newRWFileHandle: err=<nil>
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:51 DEBUG : file1: >Open: fd=file1 (rw), err=<nil>
2024/12/19 02:46:51 DEBUG : file1: >OpenFile: fd=file1 (rw), err=<nil>
2024/12/19 02:46:51 DEBUG : file1(0xc0001314c0): close: 
2024/12/19 02:46:51 DEBUG : file1: vfs cache: setting modification time to 2024-12-19 02:46:51.564062583 +0000 UTC m=+2.638092081
2024/12/19 02:46:51 INFO  : file1: vfs cache: queuing for upload in 100ms
2024/12/19 02:46:51 DEBUG : file1(0xc0001314c0): >close: err=<nil>
2024/12/19 02:46:51 DEBUG : file2: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2024/12/19 02:46:51 DEBUG : file2: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2024/12/19 02:46:51 DEBUG : file2: newRWFileHandle: 
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): openPending: 
2024/12/19 02:46:51 DEBUG : file2: vfs cache: truncate to size=0 (not needed as size correct)
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file2"
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): >openPending: err=<nil>
2024/12/19 02:46:51 DEBUG : file2: >newRWFileHandle: err=<nil>
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file2"
2024/12/19 02:46:51 DEBUG : file2: >Open: fd=file2 (rw), err=<nil>
2024/12/19 02:46:51 DEBUG : file2: >OpenFile: fd=file2 (rw), err=<nil>
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): RWFileHandle.Flush
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): RWFileHandle.Release
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): close: 
2024/12/19 02:46:51 DEBUG : file2: vfs cache: setting modification time to 2024-12-19 02:46:51.564987452 +0000 UTC m=+2.639016940
2024/12/19 02:46:51 INFO  : file2: vfs cache: queuing for upload in 100ms
2024/12/19 02:46:51 DEBUG : file2(0xc000131580): >close: err=<nil>
2024/12/19 02:46:51 DEBUG : WaitForWriters: timeout=30s
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 10ms
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 20ms
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 40ms
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 80ms
2024/12/19 02:46:51 DEBUG : file2: vfs cache: starting upload
2024/12/19 02:46:51 DEBUG : file1: vfs cache: starting upload
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file1 # [HashesOption([])] 0
2024/12/19 02:46:51 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file2 # [HashesOption([])] 0
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 2 cache items in use, waiting 160ms
2024/12/19 02:46:51 INFO  : file1: Copied (new)
2024/12/19 02:46:51 DEBUG : file1: vfs cache: fingerprint now "0,2024-12-19 02:46:51.564062583 +0000 UTC"
2024/12/19 02:46:51 DEBUG : file1: vfs cache: writeback object to VFS layer
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/12/19 02:46:51 INFO  : file1: vfs cache: upload succeeded try #1
2024/12/19 02:46:51 DEBUG : : Looking for writers
2024/12/19 02:46:51 DEBUG : file1: reading active writers
2024/12/19 02:46:51 DEBUG : file2: reading active writers
2024/12/19 02:46:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2024/12/19 02:46:51 INFO  : file2: Copied (new)
2024/12/19 02:46:51 DEBUG : file2: vfs cache: fingerprint now "0,2024-12-19 02:46:51.564987452 +0000 UTC"
2024/12/19 02:46:51 DEBUG : file2: vfs cache: writeback object to VFS layer
2024/12/19 02:46:51 DEBUG : : Added virtual directory entry vAddFile: "file2"
2024/12/19 02:46:51 INFO  : file2: vfs cache: upload succeeded try #1
2024/12/19 02:46:52 DEBUG : : Looking for writers
2024/12/19 02:46:52 DEBUG : file1: reading active writers
2024/12/19 02:46:52 DEBUG : file2: reading active writers
2024/12/19 02:46:52 DEBUG : >WaitForWriters: 
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:52 DEBUG : WaitForWriters: timeout=30s
2024/12/19 02:46:52 DEBUG : : Looking for writers
2024/12/19 02:46:52 DEBUG : file1: reading active writers
2024/12/19 02:46:52 DEBUG : file2: reading active writers
2024/12/19 02:46:52 DEBUG : >WaitForWriters: 
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:52 DEBUG : vfs cache: cleaner exiting
2024/12/19 02:46:52 DEBUG : file1: rm sj://rclone-test-casajif1xeyo/file1
2024/12/19 02:46:52 DEBUG : file2: rm sj://rclone-test-casajif1xeyo/file2
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
--- PASS: TestRWFileHandleWriteNoWrite (1.07s)
=== RUN   TestVFSStat
    run.go:180: Remote "FS sj://rclone-test-casajif1xeyo", Local "Local file system at /tmp/rclone3325143745", Modify Window "1ns"
2024/12/19 02:46:52 INFO  : FS sj://rclone-test-casajif1xeyo: poll-interval is not supported by this remote
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: mkdir -p ./
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./file1 # [] 14
2024/12/19 02:46:52 DEBUG : FS sj://rclone-test-casajif1xeyo: cp input ./dir/file2 # [] 14
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: ls ./
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: ls ./dir
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls ./dir ("rclone-test-casajif1xeyo", "dir")
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: opts &{Prefix:dir/ Cursor: Recursive:false System:true Custom:true}
2024/12/19 02:46:53 DEBUG : WaitForWriters: timeout=30s
2024/12/19 02:46:53 DEBUG : dir: Looking for writers
2024/12/19 02:46:53 DEBUG : file2: reading active writers
2024/12/19 02:46:53 DEBUG : : Looking for writers
2024/12/19 02:46:53 DEBUG : dir: reading active writers
2024/12/19 02:46:53 DEBUG : file1: reading active writers
2024/12/19 02:46:53 DEBUG : >WaitForWriters: 
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
2024/12/19 02:46:53 DEBUG : dir/file2: rm sj://rclone-test-casajif1xeyo/dir/file2
2024/12/19 02:46:53 DEBUG : file1: rm sj://rclone-test-casajif1xeyo/file1
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: rmdir ./dir
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: ls -R ./
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: OBJ ls -R ./ ("rclone-test-casajif1xeyo", "")
--- PASS: TestVFSStat (0.90s)
PASS
2024/12/19 02:46:53 DEBUG : FS sj://rclone-test-casajif1xeyo: Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^(TestRWFileHandleWriteAt|TestRWFileHandleWriteNoWrite|TestVFSStat)$'" - Finished OK in 4.6406665s (try 2/5)