Opened 22 months ago

Last modified 6 weeks ago

#23620 new defect

Tor lies about "Optimistically trying directory fetches again"

Reported by: teor Owned by:
Priority: Medium Milestone: Tor: unspecified
Component: Core Tor/Tor Version: Tor: 0.3.0.10
Severity: Normal Keywords: 033-triage-20180320, 033-removed-20180320, needs-proposal
Cc: Actual Points:
Parent ID: #21969 Points: 1
Reviewer: Sponsor:

Description

My tor hasn't sent anything to the network for 15 minutes, but it keeps telling me Application request when we haven't used client functionality lately. Optimistically trying directory fetches again.

My laptop was asleep, and when I woke it up, Tor tried to connect to the network a few times, and then ended up in this state.

21/9/17, 12:35:33.100 [NOTICE] Average packaged cell fullness: 67.067%. TLS write overhead: 4% 
21/9/17, 12:46:37.900 [NOTICE] Tor has successfully opened a circuit. Looks like client functionality is working. 
21/9/17, 12:46:37.900 [NOTICE] Tor has successfully opened a circuit. Looks like client functionality is working. 
22/9/17, 11:42:02.700 [NOTICE] Your system clock just jumped 65128 seconds forward; assuming established circuits no longer work. 
22/9/17, 11:42:02.700 [NOTICE] Heartbeat: Tor's uptime is 4 days 16:01 hours, with 0 circuits open. I've sent 11.27 MB and received 124.39 MB. 
22/9/17, 11:42:02.700 [NOTICE] Average packaged cell fullness: 67.151%. TLS write overhead: 4% 
22/9/17, 11:52:39.800 [NOTICE] Application request when we haven't used client functionality lately. Optimistically trying directory fetches again. 
22/9/17, 11:54:39.000 [NOTICE] Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) 
22/9/17, 12:05:05.400 [NOTICE] Application request when we haven't used client functionality lately. Optimistically trying directory fetches again.
(repeats many times)

Child Tickets

Change History (8)

comment:1 Changed 22 months ago by teor

Version: Tor: 0.3.0.11Tor: 0.3.0.10

I'm using Tor Browser 7.0.5 on macOS 10.12 with the packaged version of tor 0.3.0.10.

comment:2 Changed 21 months ago by nickm

Hm. We could easily just change the warning to end "Optimistically marking [any] stalled directory downloads as retryable" ?

And we could change "Application request" to better describe whether it's a user request or an internally-generated stream?

This looks, maybe, like some kind of crazy deadlock where we can't download directory info without circuits, and we can't open circuits without directory info? Did we have one of those recently?

comment:3 in reply to:  2 Changed 21 months ago by teor

Parent ID: #21969

Replying to nickm:

Hm. We could easily just change the warning to end "Optimistically marking [any] stalled directory downloads as retryable" ?

And we could change "Application request" to better describe whether it's a user request or an internally-generated stream?

This looks, maybe, like some kind of crazy deadlock where we can't download directory info without circuits, and we can't open circuits without directory info? Did we have one of those recently?

Yes, it happens in #21969 and children. There seem to be a few root causes, so it's plausible that they could cause this bug,

Setting parent to #21969, so we close this when it closes.

comment:4 Changed 19 months ago by asn

Milestone: Tor: 0.3.2.x-finalTor: 0.3.3.x-final

comment:5 Changed 16 months ago by nickm

Keywords: 033-triage-20180320 added

Marking all tickets reached by current round of 033 triage.

comment:6 Changed 16 months ago by nickm

Keywords: 033-removed-20180320 added

Mark all not-already-included tickets as pending review for removal from 0.3.3 milestone.

comment:7 Changed 16 months ago by nickm

Milestone: Tor: 0.3.3.x-finalTor: unspecified

These tickets were marked as removed, and nobody has said that they can fix them. Let's remember to look at 033-removed-20180320 as we re-evaluate our triage process, to see whether we're triaging out unnecessarily, and to evaluate whether we're deferring anything unnecessarily. But for now, we can't do these: we need to fix the 033-must stuff now.

comment:8 Changed 6 weeks ago by teor

Keywords: needs-proposal added

#16844 and #21969 have conflicting goals, so we need to write a proposal that balances these goals. See #30817.

Note: See TracTickets for help on using tickets.