<7>DEBUG : rclone: Version "v1.60.0-DEV" starting with parameters ["./rclone" "mount" "storj-s3-pasnox-xxx:xxx" "/home/pasnox/Projects/rclone/_tmp/bin/../XXXX" "--async-read" "--allow-other" "--attr-timeout=1h" "--dir-cache-time=720h" "--poll-interval=0" "--vfs-cache-max-age=720h" "--no-checksum" "--no-modtime" "--no-seek" "--read-only" "--use-server-modtime" "--vfs-cache-mode=full" "--config" "/home/pasnox/Projects/rclone/_tmp/bin/../config/rclone.conf" "--cache-dir=/home/pasnox/Projects/rclone/_tmp/bin/../cache" "-vv"]
<7>DEBUG : rclone: systemd logging support activated
<7>DEBUG : Creating backend with remote "storj-s3-pasnox-xxx:xxx"
<7>DEBUG : Using config file from "/home/pasnox/Projects/rclone/_tmp/config/rclone.conf"
<7>DEBUG : vfs cache: root is "/home/pasnox/Projects/rclone/_tmp/cache"
<7>DEBUG : vfs cache: data root is "/home/pasnox/Projects/rclone/_tmp/cache/vfs/storj-s3-pasnox-xxx/xxx"
<7>DEBUG : vfs cache: metadata root is "/home/pasnox/Projects/rclone/_tmp/cache/vfsMeta/storj-s3-pasnox-xxx/xxx"
<7>DEBUG : Creating backend with remote "/home/pasnox/Projects/rclone/_tmp/cache/vfs/storj-s3-pasnox-xxx/xxx"
<7>DEBUG : Creating backend with remote "/home/pasnox/Projects/rclone/_tmp/cache/vfsMeta/storj-s3-pasnox-xxx/xxx"
<7>DEBUG : S3 bucket xxx: Mounting on "/home/pasnox/Projects/rclone/_tmp/bin/../XXXX"
<6>INFO : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
<7>DEBUG : : Root:
<7>DEBUG : : >Root: node=/, err=<nil>
<7>DEBUG : /: Lookup: name="XXXX"
<7>DEBUG : /: >Lookup: node=XXXX/, err=<nil>
<7>DEBUG : XXXX/: Attr:
<7>DEBUG : XXXX/: >Attr: attr=valid=1h0m0s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
<7>DEBUG : XXXX/: Lookup: name="Sub"
<7>DEBUG : XXXX/: >Lookup: node=XXXX/Sub/, err=<nil>
<7>DEBUG : XXXX/Sub/: Attr:
<7>DEBUG : XXXX/Sub/: >Attr: attr=valid=1h0m0s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
<7>DEBUG : XXXX/Sub/: Lookup: name="file.zip"
<7>DEBUG : XXXX/Sub/: >Lookup: node=XXXX/Sub/file.zip, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: Attr:
<7>DEBUG : XXXX/Sub/file.zip: >Attr: a=valid=1h0m0s ino=0 size=1548250 mode=-rw-r--r--, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: Open: flags=OpenReadOnly
<7>DEBUG : XXXX/Sub/file.zip: Open: flags=O_RDONLY
<7>DEBUG : XXXX/Sub/file.zip: newRWFileHandle:
<7>DEBUG : XXXX/Sub/file.zip: >newRWFileHandle: err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: >Open: fd=XXXX/Sub/file.zip (rw), err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: >Open: fh=&{XXXX/Sub/file.zip (rw)}, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=0
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=0
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=131072
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): openPending:
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: checking remote fingerprint "1548250,2022-02-11 21:34:47.93 +0000 UTC,b5bb0b2b8657ebf16dd0821ebd5459bd" against cached fingerprint ""
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: truncate to size=1548250
<7>DEBUG : XXXX/Sub: Added virtual directory entry vAddFile: "file.zip"
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >openPending: err=<nil>
<7>DEBUG : vfs cache: looking for range={Pos:0 Size:131072} in [] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.RangeSeek from -1 to 0 length -1
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=131072
<7>DEBUG : vfs cache: looking for range={Pos:131072 Size:131072} in [] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at -1 length 4096 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.openRange at 0 length 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=262144
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=262144
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=393216
<7>DEBUG : vfs cache: looking for range={Pos:262144 Size:131072} in [{Pos:0 Size:258048}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=393216
<7>DEBUG : vfs cache: looking for range={Pos:393216 Size:131072} in [{Pos:0 Size:258048}] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 520192 length 524288 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=524288
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=655360
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=524288
<7>DEBUG : vfs cache: looking for range={Pos:524288 Size:131072} in [{Pos:0 Size:520192}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=655360
<7>DEBUG : vfs cache: looking for range={Pos:655360 Size:131072} in [{Pos:0 Size:520192}] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 1044480 length 1048576 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=917504
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=917504
<7>DEBUG : vfs cache: looking for range={Pos:917504 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=786432
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=786432
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1048576
<7>DEBUG : vfs cache: looking for range={Pos:786432 Size:131072} in [{Pos:0 Size:1044480}] - present true
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=1048576
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1179648
<7>DEBUG : vfs cache: looking for range={Pos:1048576 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=1179648
<7>DEBUG : vfs cache: looking for range={Pos:1179648 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1310720
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=106496, offset=1441792
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=131072, off=1310720
<7>DEBUG : vfs cache: looking for range={Pos:1310720 Size:131072} in [{Pos:0 Size:1548250}] - present true
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): _readAt: size=106496, off=1441792
<7>DEBUG : vfs cache: looking for range={Pos:1441792 Size:106458} in [{Pos:0 Size:1548250}] - present true
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >_readAt: n=106458, err=EOF
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=106458, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Flush:
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): RWFileHandle.Flush
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Flush: err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Release:
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): RWFileHandle.Release
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): close:
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: setting modification time to 2022-02-11 21:34:47 +0000 UTC
<7>DEBUG : XXXX/Sub/file.zip(0xc0012fc0c0): >close: err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Release: err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: Open: flags=OpenReadOnly
<7>DEBUG : XXXX/Sub/file.zip: Open: flags=O_RDONLY
<7>DEBUG : XXXX/Sub/file.zip: newRWFileHandle:
<7>DEBUG : XXXX/Sub/file.zip: >newRWFileHandle: err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: >Open: fd=XXXX/Sub/file.zip (rw), err=<nil>
<7>DEBUG : XXXX/Sub/file.zip: >Open: fh=&{XXXX/Sub/file.zip (rw)}, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=0
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=0
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=131072
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): openPending:
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: checking remote fingerprint "1548250,2022-02-11 21:34:47 +0000 UTC,b5bb0b2b8657ebf16dd0821ebd5459bd" against cached fingerprint "1548250,2022-02-11 21:34:47.93 +0000 UTC,b5bb0b2b8657ebf16dd0821ebd5459bd"
<6>INFO : XXXX/Sub/file.zip: vfs cache: removed cache file as stale (remote is different)
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: removed metadata from cache as stale (remote is different)
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: truncate to size=1548250
<7>DEBUG : XXXX/Sub: Added virtual directory entry vAddFile: "file.zip"
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >openPending: err=<nil>
<7>DEBUG : vfs cache: looking for range={Pos:0 Size:131072} in [] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.RangeSeek from -1 to 0 length -1
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=131072
<7>DEBUG : vfs cache: looking for range={Pos:131072 Size:131072} in [] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at -1 length 4096 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.openRange at 0 length 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=262144
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=262144
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=393216
<7>DEBUG : vfs cache: looking for range={Pos:262144 Size:131072} in [{Pos:0 Size:258048}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=393216
<7>DEBUG : vfs cache: looking for range={Pos:393216 Size:131072} in [{Pos:0 Size:258048}] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 520192 length 524288 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=524288
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=655360
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=524288
<7>DEBUG : vfs cache: looking for range={Pos:524288 Size:131072} in [{Pos:0 Size:520192}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=655360
<7>DEBUG : vfs cache: looking for range={Pos:655360 Size:131072} in [{Pos:0 Size:520192}] - present false
<7>DEBUG : XXXX/Sub/file.zip: ChunkedReader.Read at 1044480 length 1048576 chunkOffset 0 chunkSize 134217728
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=786432
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=786432
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1048576
<7>DEBUG : vfs cache: looking for range={Pos:786432 Size:131072} in [{Pos:0 Size:1044480}] - present true
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=917504
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1179648
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=1048576
<7>DEBUG : vfs cache: looking for range={Pos:1048576 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=917504
<7>DEBUG : vfs cache: looking for range={Pos:917504 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=1179648
<7>DEBUG : vfs cache: looking for range={Pos:1179648 Size:131072} in [{Pos:0 Size:1044480}] - present false
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=131072, offset=1310720
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Read: len=106496, offset=1441792
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=131072, off=1310720
<7>DEBUG : vfs cache: looking for range={Pos:1310720 Size:131072} in [{Pos:0 Size:1548250}] - present true
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=131072, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=131072, err=<nil>
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): _readAt: size=106496, off=1441792
<7>DEBUG : vfs cache: looking for range={Pos:1441792 Size:106458} in [{Pos:0 Size:1548250}] - present true
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >_readAt: n=106458, err=EOF
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Read: read=106458, err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Flush:
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): RWFileHandle.Flush
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Flush: err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: Release:
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): RWFileHandle.Release
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): close:
<7>DEBUG : XXXX/Sub/file.zip: vfs cache: setting modification time to 2022-02-11 21:34:47 +0000 UTC
<7>DEBUG : XXXX/Sub/file.zip(0xc000067900): >close: err=<nil>
<7>DEBUG : &{XXXX/Sub/file.zip (rw)}: >Release: err=<nil>