Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

drive: Significant slowdown for accessing files in the range of 35MB - 100MB #2612

Closed
jm-85 opened this issue Sep 26, 2018 · 6 comments
Closed

drive: Significant slowdown for accessing files in the range of 35MB - 100MB #2612

jm-85 opened this issue Sep 26, 2018 · 6 comments
Labels
bug Remote: Drive waiting for reply...
Milestone
Known Problem

Comments

@jm-85
Copy link

jm-85 commented Sep 26, 2018 •
edited by ncw
Loading

What is the problem you are having with rclone?

Unexpected slow responses for accessing files with sizes in the range of 35MB to 100MB
Files of this type often take an additional 30s to 1m to access.
Note this does not occur every time.
Typical transfer time is less than 2s for files above or below the aforementioned range.

What is your rclone version (output from rclone version)

rclone-beta-latest --version
rclone v1.43-116-g22ac80e8-beta

  • os/arch: linux/amd64
  • go version: go1.11

Which OS you are using and how many bits (eg Windows 7, 64 bit)

Ubuntu 18.04.1 LTS

Which cloud storage system are you using? (eg Google Drive)

Google Drive

The command you were trying to run (eg rclone copy /tmp remote:tmp)

Rclone remote mounted with:
/usr/bin/rclone-beta-latest mount
--allow-other
--buffer-size 1G
--dir-cache-time 72h
--fast-list
--log-file='/opt/logs/rclone-debug.log'
--log-level "DEBUG"
--read-only
--vfs-read-chunk-size 128M
--vfs-read-chunk-size-limit off
plex-debug:/debug
/debug

Testing file accessibility with:
cp /debug/64M.file

Files were creating using:
dd if=/dev/zero of= bs= count=1

A log from the command with the -vv flag (eg output from rclone -vv copy /tmp remote:tmp)

##Note the time gap between 64M.file: ChunkedReader.openRange at 0 length 134217728 and
##64M.file: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728

2018/09/26 14:23:37 DEBUG : /: Lookup: name="64M.file"
2018/09/26 14:23:37 DEBUG : /: >Lookup: node=64M.file, err=<nil>
2018/09/26 14:23:37 DEBUG : 64M.file: Attr:
2018/09/26 14:23:37 DEBUG : 64M.file: >Attr: a=valid=1s ino=0 size=67108864 mode=-rw-r--r--, err=<nil>
2018/09/26 14:23:37 DEBUG : /: Lookup: name="64M.file"
2018/09/26 14:23:37 DEBUG : /: >Lookup: node=64M.file, err=<nil>
2018/09/26 14:23:37 DEBUG : 64M.file: Attr:
2018/09/26 14:23:37 DEBUG : 64M.file: >Attr: a=valid=1s ino=0 size=67108864 mode=-rw-r--r--, err=<nil>
2018/09/26 14:23:37 DEBUG : 64M.file: Open: flags=OpenReadOnly
2018/09/26 14:23:37 DEBUG : 64M.file: Open: flags=O_RDONLY
2018/09/26 14:23:37 DEBUG : 64M.file: >Open: fd=64M.file (r), err=<nil>
2018/09/26 14:23:37 DEBUG : 64M.file: >Open: fh=&{64M.file (r)}, err=<nil>
2018/09/26 14:23:37 DEBUG : &{64M.file (r)}: Read: len=131072, offset=0
2018/09/26 14:23:37 DEBUG : 64M.file: ChunkedReader.openRange at 0 length 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 520192 length 524288 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 1044480 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=131072
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 2093056 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=262144
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=393216
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=524288
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=655360
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=786432
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=917504
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 3141632 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1048576
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1179648
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 4190208 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1310720
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1441792
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 5238784 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1572864
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1703936
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1835008
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=1966080
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2097152
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2228224
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2359296
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2490368
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 6287360 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2621440
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2752512
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=2883584
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3014656
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3145728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3276800
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 7335936 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3407872
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3538944
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3670016
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3801088
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=3932160
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 8384512 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4063232
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 9433088 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 10481664 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4194304
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4325376
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4456448
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4587520
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4718592
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4849664
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=4980736
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5111808
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5242880
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5373952
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5505024
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5636096
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5767168
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=5898240
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 11530240 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6029312
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6160384
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6291456
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6422528
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6553600
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6684672
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6815744
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 12578816 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=6946816
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7077888
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7208960
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7340032
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7471104
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7602176
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7733248
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7864320
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=7995392
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 13627392 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8126464
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8257536
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8388608
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8519680
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8650752
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8781824
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 14675968 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=8912896
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9043968
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9175040
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9306112
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 15724544 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9437184
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 16773120 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9568256
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9699328
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9830400
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 17821696 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=9961472
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 18870272 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10092544
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10223616
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10354688
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 19918848 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10485760
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 20967424 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10616832
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 22016000 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10747904
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=10878976
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11010048
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11141120
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11272192
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11403264
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11534336
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11665408
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11796480
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=11927552
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12058624
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12189696
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12320768
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12451840
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 23064576 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12582912
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12713984
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12845056
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=12976128
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13107200
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 24113152 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13238272
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13369344
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13500416
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 25161728 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13631488
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13762560
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=13893632
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14024704
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 26210304 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14155776
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14286848
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14417920
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14548992
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 27258880 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14680064
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14811136
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=14942208
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 28307456 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15073280
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15204352
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15335424
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15466496
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15597568
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15728640
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15859712
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=15990784
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16121856
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 29356032 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16252928
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 30404608 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16384000
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16515072
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 31453184 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16646144
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16777216
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=16908288
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17039360
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17170432
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17301504
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17432576
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17563648
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17694720
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17825792
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=17956864
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18087936
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18219008
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18350080
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 32501760 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18481152
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18612224
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18743296
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 33550336 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=18874368
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19005440
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19136512
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19267584
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 34598912 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19398656
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19529728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19660800
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19791872
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=19922944
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20054016
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20185088
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20316160
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20447232
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 35647488 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20578304
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20709376
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20840448
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=20971520
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21102592
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 36696064 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21233664
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21364736
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21495808
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21626880
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21757952
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 37744640 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=21889024
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22020096
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22151168
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 38793216 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22282240
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22413312
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22544384
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 39841792 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 40890368 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22675456
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 41938944 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22806528
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=22937600
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23068672
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23199744
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23330816
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23461888
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23592960
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 42987520 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23724032
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23855104
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=23986176
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24117248
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24248320
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24379392
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24510464
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24641536
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24772608
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=24903680
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25034752
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25165824
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25296896
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25427968
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25559040
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 44036096 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25690112
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25821184
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 45084672 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=25952256
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26083328
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26214400
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 46133248 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26345472
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26476544
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26607616
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26738688
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=26869760
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27000832
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27131904
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27262976
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27394048
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27525120
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 47181824 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27656192
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27787264
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=27918336
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28049408
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28180480
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 48230400 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28311552
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28442624
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28573696
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 49278976 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28704768
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 50327552 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28835840
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=28966912
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29097984
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29229056
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 51376128 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29360128
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 52424704 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29491200
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29622272
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29753344
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=29884416
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30015488
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30146560
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30277632
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30408704
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30539776
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30670848
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30801920
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=30932992
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 53473280 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31064064
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31195136
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31326208
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31457280
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31588352
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 54521856 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31719424
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31850496
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=31981568
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32112640
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32243712
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32374784
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32505856
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32636928
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 55570432 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32768000
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 56619008 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=32899072
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33030144
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33161216
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 57667584 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33292288
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33423360
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33554432
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33685504
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33816576
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 58716160 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=33947648
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 59764736 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34078720
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 60813312 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 61861888 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 62910464 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34209792
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34340864
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34471936
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34603008
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 63959040 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34734080
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34865152
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=34996224
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 65007616 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35127296
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35258368
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35389440
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35520512
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35651584
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 66056192 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35782656
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=35913728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36044800
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36175872
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36306944
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : 64M.file: ChunkedReader.Read at 67104768 length 1048576 chunkOffset 0 chunkSize 134217728
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36438016
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36569088
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36700160
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36831232
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=36962304
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37093376
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37224448
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37355520
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37486592
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37617664
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37748736
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=37879808
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38010880
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38141952
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38273024
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:07 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38404096
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38535168
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38666240
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38797312
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=38928384
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39059456
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39190528
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39321600
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39452672
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39583744
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39714816
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39845888
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=39976960
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40108032
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40239104
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40370176
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40501248
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40632320
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40763392
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=40894464
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41025536
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41156608
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41287680
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41418752
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41549824
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41680896
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41811968
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=41943040
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42074112
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42205184
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42336256
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42467328
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42598400
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42729472
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42860544
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=42991616
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43122688
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43253760
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43384832
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43515904
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43646976
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43778048
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=43909120
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44040192
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44171264
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44302336
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44433408
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44564480
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44695552
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44826624
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=44957696
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45088768
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45219840
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45350912
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45481984
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45613056
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45744128
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=45875200
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46006272
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46137344
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46268416
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46399488
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46530560
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46661632
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46792704
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=46923776
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47054848
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47185920
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47316992
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47448064
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47579136
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47710208
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47841280
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=47972352
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48103424
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48234496
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48365568
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48496640
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48627712
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48758784
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=48889856
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49020928
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49152000
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49283072
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49414144
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49545216
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49676288
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49807360
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=49938432
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50069504
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50200576
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50331648
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50462720
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50593792
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50724864
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50855936
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=50987008
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51118080
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51249152
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51380224
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51511296
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51642368
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51773440
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=51904512
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52035584
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52166656
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52297728
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52428800
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52559872
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52690944
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52822016
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=52953088
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53084160
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53215232
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53346304
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53477376
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53608448
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53739520
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=53870592
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54001664
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54132736
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54263808
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54394880
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54525952
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54657024
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54788096
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=54919168
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55050240
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55181312
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55312384
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55443456
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55574528
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55705600
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55836672
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=55967744
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56098816
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56229888
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56360960
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56492032
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56623104
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56754176
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=56885248
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57016320
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57147392
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57278464
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57409536
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57540608
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57671680
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57802752
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=57933824
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58064896
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58195968
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58327040
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58458112
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58589184
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58720256
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58851328
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=58982400
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59113472
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59244544
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59375616
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59506688
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59637760
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59768832
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=59899904
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60030976
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60162048
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60293120
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60424192
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60555264
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60686336
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60817408
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=60948480
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61079552
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61210624
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61341696
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61472768
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61603840
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61734912
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61865984
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=61997056
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62128128
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62259200
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62390272
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62521344
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62652416
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62783488
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=62914560
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63045632
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63176704
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63307776
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63438848
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63569920
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63700992
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63832064
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=63963136
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64094208
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64225280
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64356352
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64487424
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64618496
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64749568
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=64880640
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65011712
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65142784
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65273856
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65404928
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65536000
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65667072
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65798144
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=65929216
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66060288
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66191360
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66322432
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66453504
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66584576
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66715648
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66846720
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=131072, offset=66977792
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=131072, err=<nil>
2018/09/26 14:24:08 DEBUG : 64M.file: Attr:
2018/09/26 14:24:08 DEBUG : 64M.file: >Attr: a=valid=1s ino=0 size=67108864 mode=-rw-r--r--, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Read: len=4096, offset=67108864
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Read: read=0, err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Flush:
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Flush: err=<nil>
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: Release:
2018/09/26 14:24:08 DEBUG : 64M.file: ReadFileHandle.Release closing
2018/09/26 14:24:08 DEBUG : &{64M.file (r)}: >Release: err=<nil>
@ncw
Copy link
Member

ncw commented Sep 27, 2018 •
edited
Loading

I managed to replicate this.

I also managed to replicate without mount.

This has the 30 pause every 3 tries or so:

rclone cat -vv TestDrive:test/test-2612/64M | pv >/dev/null

However there is a work-around using --drive-v2-download-min-size 0 which has worked for 20 in a row:

rclone cat --drive-v2-download-min-size 0 -vv TestDrive:test/test-2612/64M | pv >/dev/null

Which kind of indicates that this is a google drive problem rather than an rclone problem.

@B4dM4n any ideas? You were going to report an issue about the whole v2 vs v3 API for drive weren't you?

See also: #2573

@ncw ncw added this to the v1.44 milestone Sep 27, 2018
@ncw ncw changed the title Significant slowdown for accessing files in the range of 35MB - 100MB drive: Significant slowdown for accessing files in the range of 35MB - 100MB Sep 27, 2018
@seuffert
Copy link
Contributor

seuffert commented Oct 15, 2018 •
edited
Loading

i experienced the same thing, sudden extreme slowdown (to ~5mbit/s). using the --drive-v2-download-min-size 0 helped. a curious detail: it is only reproducible with some particular files in my drive, others were not effected.

EDIT; dang it. it also happend with the drive v2 api... anything i can provide to help with this? i ran some tests. If i download the file at full speed from the mount (to /dev/null) the slowdown never happens, only if the requesting process is reading at a moderate speed.

@seuffert
Copy link
Contributor

Well it seems i was barking at the wrong tree. My issue is likely related to a kernel bug.
See https://forum.rclone.org/t/sudden-drop-in-download-bandwidth-during-transfer/7255/14 and https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1796895)

@ncw ncw modified the milestones: v1.44, v1.45 Oct 19, 2018
@ncw
Copy link
Member

ncw commented Oct 19, 2018

@seuffert well done for tracking down the problem! Kernel bugs are tricky at the best of times.

@ncw ncw modified the milestones: v1.45, v1.46 Nov 24, 2018
@ncw ncw modified the milestones: v1.46, v1.47 Feb 9, 2019
@ncw ncw modified the milestones: v1.47, v1.48 Apr 15, 2019
@ncw ncw modified the milestones: v1.48, v1.49 Jun 19, 2019
@ncw ncw modified the milestones: v1.49, v1.50 Aug 27, 2019
@ncw ncw modified the milestones: v1.50, Known Problem Nov 14, 2019
@ivandeex
Copy link
Member

ivandeex commented Feb 9, 2021

@jm-85 Does the problem reproduce with rclone 1.54?

@ivandeex
Copy link
Member

Closing stale requests

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Remote: Drive waiting for reply...
Projects
None yet
Milestone
Known Problem
Development

No branches or pull requests
4 participants
@ncw @seuffert @jm-85 @ivandeex