[tor-bugs] #30926 [Core Tor/Tor]: OnionPerf requests to onion service fail close towards completion with TGEN/READ error

[Tor Bug Tracker & Wiki]

#30926: OnionPerf requests to onion service fail close towards completion with
TGEN/READ error
-------------------------------------------------+-------------------------
 Reporter:  karsten                              |          Owner:  (none)
     Type:  defect                               |         Status:
                                                 |  needs_information
 Priority:  Medium                               |      Milestone:  Tor:
                                                 |  0.4.1.x-final
Component:  Core Tor/Tor                         |        Version:
 Severity:  Normal                               |     Resolution:
 Keywords:  041-should tor-hs, improve-          |  Actual Points:
  performance                                    |
Parent ID:                                       |         Points:
 Reviewer:                                       |        Sponsor:
-------------------------------------------------+-------------------------

Comment (by karsten):

 Replying to [comment:3 asn]:
 > This tor version seems to be quite old (maybe 029?).

 Maybe, yes. But I don't think that this matters here. I just attached logs
 from op-ab (running 0.3.5.7-dev) for another case, this time a 1 MiB
 download from earlier this month that stopped after receiving 91.24% of
 the response:

 {{{
 2019-06-07 22:39:55 1559943595.389059 [info] [shd-tgen-transfer.c:1534]
 [_tgentransfer_log] [transfer-status] transport
 TCP,16,localhost:127.0.0.1:37054,localhost:127.0.0.1:48240,5bbbmrgzxhiykhah.onion:0.0.0.0:443,state=SUCCESS,error=NONE
 transfer transfer1m,3056,op-ab,GET,1048576,op-
 ab,834,state=PAYLOAD,error=NONE total-bytes-read=956746 total-bytes-
 write=55 payload-bytes-read=956715/1048576 (91.24%)
 2019-06-07 22:39:56 1559943596.317712 [info] [shd-tgen-transport.c:408]
 [tgentransport_read] read(): socket 16 closed unexpectedly
 2019-06-07 22:39:56 1559943596.317761 [info] [shd-tgen-transport.c:203]
 [_tgentransport_changeState] transport
 TCP,16,localhost:127.0.0.1:37054,localhost:127.0.0.1:48240,5bbbmrgzxhiykhah.onion:0.0.0.0:443,state=SUCCESS,error=NONE
 moving from state SUCCESS to state ERROR
 2019-06-07 22:39:56 1559943596.317787 [info] [shd-tgen-transport.c:211]
 [_tgentransport_changeError] transport
 TCP,16,localhost:127.0.0.1:37054,localhost:127.0.0.1:48240,5bbbmrgzxhiykhah.onion:0.0.0.0:443,state=ERROR,error=NONE
 moving from error NONE to error READ
 2019-06-07 22:39:56 1559943596.317807 [info] [shd-tgen-transfer.c:363]
 [_tgentransfer_changeState] transfer transfer1m,3056,op-ab,GET,1048576,op-
 ab,834,state=PAYLOAD,error=NONE moving from state PAYLOAD to state ERROR
 2019-06-07 22:39:56 1559943596.317829 [info] [shd-tgen-transfer.c:371]
 [_tgentransfer_changeError] transfer transfer1m,3056,op-ab,GET,1048576,op-
 ab,834,state=ERROR,error=NONE moving from error NONE to error READ
 2019-06-07 22:39:56 1559943596.317850 [critical] [shd-tgen-transfer.c:684]
 [_tgentransfer_readPayload] read(): transport
 TCP,16,localhost:127.0.0.1:37054,localhost:127.0.0.1:48240,5bbbmrgzxhiykhah.onion:0.0.0.0:443,state=ERROR,error=READ
 transfer transfer1m,3056,op-ab,GET,1048576,op-
 ab,834,state=ERROR,error=READ closed unexpectedly
 2019-06-07 22:39:56 1559943596.317899 [message] [shd-tgen-transfer.c:1504]
 [_tgentransfer_log] [transfer-error] transport
 TCP,16,localhost:127.0.0.1:37054,localhost:127.0.0.1:48240,5bbbmrgzxhiykhah.onion:0.0.0.0:443,state=ERROR,error=READ
 transfer transfer1m,3056,op-ab,GET,1048576,op-
 ab,834,state=ERROR,error=READ total-bytes-read=956746 total-bytes-write=55
 payload-bytes-read=956715/1048576 (91.24%) usecs-to-socket-create=16
 usecs-to-socket-connect=159 usecs-to-proxy-init=198 usecs-to-proxy-
 choice=899 usecs-to-proxy-request=976 usecs-to-proxy-response=-1 usecs-to-
 command=4685610 usecs-to-response=6607887 usecs-to-first-byte=6607924
 usecs-to-last-byte=-1 usecs-to-checksum=-1
 2019-06-07 22:39:56 1559943596.317932 [info] [shd-tgen-transport.c:321]
 [_tgentransport_free] closing transport socket for fd 16
 }}}

--
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/30926#comment:6>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online