*route_replies: consistent timeout on Last request: MODE #gaygeeks-asl b #638

Open
opened 2026-10-05 04:35:29 +02:00 by adam · 5 comments
Owner

I'm using Colloquy Version 2.4.3 (6011) to connect to ZNC ZNC 1.7.x-git-481-2cb6599 IPv6: yes, SSL: yes, DNS: threads, charset: yes running on Lubuntu 15.10 wily.

Every time I open Colloquy and connect to ZNC again, I get the exact same 16 lines from route_replies:

<*route_replies> This module hit a timeout which is probably a connectivity issue.
<*route_replies> However, if you can provide steps to reproduce this issue, please do report a bug.
<*route_replies> To disable this message, do "/msg *route_replies silent yes"
<*route_replies> Last request: MODE #gaygeeks-asl b
<*route_replies> Expected replies:
<*route_replies> 482 (last)
<*route_replies> 346
<*route_replies> 347 (last)
<*route_replies> 367
<*route_replies> 368 (last)
<*route_replies> 348
<*route_replies> 349 (last)
<*route_replies> 467 (last)
<*route_replies> 472 (last)
<*route_replies> 501 (last)
<*route_replies> 502 (last)

<*route_replies> Last request: MODE #gaygeeks-asl b refers to the channel on freenode.

I'm not sure when this started exactly—On occasions when I get errors from route_replies, I usually read over the errors and then ignore them as a "connectivity issue," but I eventually noticed this one was repeating.

I'm also not sure what other information would be helpful :x

Want to back this issue? Post a bounty on it! We accept bounties via Bountysource.

I'm using Colloquy `Version 2.4.3 (6011)` to connect to ZNC `ZNC 1.7.x-git-481-2cb6599 IPv6: yes, SSL: yes, DNS: threads, charset: yes` running on Lubuntu `15.10 wily`. **Every** time I open Colloquy and connect to ZNC again, I get the exact same 16 lines from route_replies: ``` <*route_replies> This module hit a timeout which is probably a connectivity issue. <*route_replies> However, if you can provide steps to reproduce this issue, please do report a bug. <*route_replies> To disable this message, do "/msg *route_replies silent yes" <*route_replies> Last request: MODE #gaygeeks-asl b <*route_replies> Expected replies: <*route_replies> 482 (last) <*route_replies> 346 <*route_replies> 347 (last) <*route_replies> 367 <*route_replies> 368 (last) <*route_replies> 348 <*route_replies> 349 (last) <*route_replies> 467 (last) <*route_replies> 472 (last) <*route_replies> 501 (last) <*route_replies> 502 (last) ``` > `<*route_replies> Last request: MODE #gaygeeks-asl b` refers to the channel on freenode. I'm not sure when this started exactly—On occasions when I get errors from route_replies, I usually read over the errors and then ignore them as a "connectivity issue," but I eventually noticed this one was repeating. I'm also not sure what other information would be helpful :x ## <bountysource-plugin> Want to back this issue? **[Post a bounty on it!](https://www.bountysource.com/issues/28338595-route_replies-consistent-timeout-on-last-request-mode-gaygeeks-asl-b?utm_campaign=plugin&utm_content=tracker%2F1759&utm_medium=issues&utm_source=github)** We accept bounties via [Bountysource](https://www.bountysource.com/?utm_campaign=plugin&utm_content=tracker%2F1759&utm_medium=issues&utm_source=github). </bountysource-plugin> <!-- github-sync-id:117481780 -->
Author
Owner

znc -D with actual replies which server sent would be helpful

`znc -D` with actual replies which server sent would be helpful <!-- github-comment-id:157566712 -->
Author
Owner

This http://pastebin.com/m7BbgiMx is the end of znc -D > znc.log trimmed from…

[2015-11-17 20:08:54.397584] (iynque/freenode) ZNC -> IRC [MODE #gaygeeks b]

…to just after I got the error from *route_replies and I stopped it:

[2015-11-17 20:09:54.538460] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :This module hit a timeout which is probably a connectivity issue.]
[2015-11-17 20:09:54.538911] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :However, if you can provide steps to reproduce this issue, please do report a bug.]
[2015-11-17 20:09:54.539350] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :To disable this message, do "/msg *route_replies silent yes"]
[2015-11-17 20:09:54.539722] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :Last request: MODE #gaygeeks b]
[2015-11-17 20:09:54.539972] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :Expected replies: ]
[2015-11-17 20:09:54.540200] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :482 (last)]
[2015-11-17 20:09:54.540413] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :346]
[2015-11-17 20:09:54.540616] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :347 (last)]
[2015-11-17 20:09:54.540827] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :367]
[2015-11-17 20:09:54.541030] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :368 (last)]
[2015-11-17 20:09:54.541239] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :348]
[2015-11-17 20:09:54.541545] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :349 (last)]
[2015-11-17 20:09:54.541786] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :467 (last)]
[2015-11-17 20:09:54.542119] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :472 (last)]
[2015-11-17 20:09:54.542504] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :501 (last)]
[2015-11-17 20:09:54.542746] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :502 (last)]

I don't know what's relevant, so I'm sorry if this is an annoyingly huge dump. I have even more if I've trimmed out too much :P

This time it was #gaygeeks, not #gaygeeks-asl, so now I'm not sure how frequently I get one or the other…

This http://pastebin.com/m7BbgiMx is the end of `znc -D > znc.log` trimmed from… ``` [2015-11-17 20:08:54.397584] (iynque/freenode) ZNC -> IRC [MODE #gaygeeks b] ``` …to just after I got the error from *route_replies and I stopped it: ``` [2015-11-17 20:09:54.538460] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :This module hit a timeout which is probably a connectivity issue.] [2015-11-17 20:09:54.538911] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :However, if you can provide steps to reproduce this issue, please do report a bug.] [2015-11-17 20:09:54.539350] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :To disable this message, do "/msg *route_replies silent yes"] [2015-11-17 20:09:54.539722] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :Last request: MODE #gaygeeks b] [2015-11-17 20:09:54.539972] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :Expected replies: ] [2015-11-17 20:09:54.540200] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :482 (last)] [2015-11-17 20:09:54.540413] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :346] [2015-11-17 20:09:54.540616] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :347 (last)] [2015-11-17 20:09:54.540827] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :367] [2015-11-17 20:09:54.541030] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :368 (last)] [2015-11-17 20:09:54.541239] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :348] [2015-11-17 20:09:54.541545] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :349 (last)] [2015-11-17 20:09:54.541786] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :467 (last)] [2015-11-17 20:09:54.542119] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :472 (last)] [2015-11-17 20:09:54.542504] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :501 (last)] [2015-11-17 20:09:54.542746] (iynque@munk/freenode) ZNC -> CLI [:*route_replies!znc@znc.in PRIVMSG iynque :502 (last)] ``` I don't know what's relevant, so I'm sorry if this is an annoyingly huge dump. I have _even more_ if I've trimmed out too much :P This time it was #gaygeeks, not #gaygeeks-asl, so now I'm not sure how frequently I get one or the other… <!-- github-comment-id:157578126 -->
Author
Owner

Eh, that pastebin contains the answer, "what IRC server returns in response to MODE". How exactly ZNC sends *route_replies message to client, is not so interesting.

But it looks like the channel's banlist is big enough, so that returning its mode takes more than 1 minute. The log is full of 367, and 368 which finishes this list isn't sent yet, while the timeout triggers.

Eh, that pastebin contains the answer, "what IRC server returns in response to MODE". How exactly ZNC sends `*route_replies` message to client, is not so interesting. But it looks like the channel's banlist is big enough, so that returning its mode takes more than 1 minute. The log is full of 367, and 368 which finishes this list isn't sent yet, while the timeout triggers. <!-- github-comment-id:157644160 -->
Author
Owner

This has become much less consistent since I first posted it. Seems the problem is caused by something other than ZNC.

This has become much less consistent since I first posted it. Seems the problem is caused by something other than ZNC. <!-- github-comment-id:160178324 -->
Author
Owner

I've explained above what happens...
Not sure why now it happens less often. Either banlist got smaller, or it just gets sent to client faster.

In any case, this needs to be fixed.

I've explained above what happens... Not sure why now it happens less often. Either banlist got smaller, or it just gets sent to client faster. In any case, this needs to be fixed. <!-- github-comment-id:160191723 -->
Sign in to join this conversation.
No labels
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: adam/znc#638