#1007 mod_bosh.lua:483: attempt to index field '?'
Reporter
Roi
Owner
Nobody
Created
Updated
Stars
★★ (2)
Tags
Priority-Medium
Type-Defect
Milestone-0.10
Status-Fixed
Roi
on
Oct 04 16:33:54 general error Top-level error, please report:
/usr/lib/prosody/modules/mod_bosh.lua:483: attempt to index field '?' (a nil value)
Oct 04 16:33:54 general error
stack traceback:
[C]: in function 'loop'
/usr/lib/prosody/net/server_event.lua:751: in function </usr/lib/prosody/net/server_event.lua:750>
[C]: in function 'xpcall'
/usr/bin/prosody:400: in function 'loop'
/usr/bin/prosody:431: in main chunk
[C]: ?
What version of the product are you using? On what operating system?
Prosody 0.10 1nightly436-1~jessie on amd64 Debian Jessie Stable.
Please provide any additional information below.
Appeared a lot on prosody.err today.
Zash
on
Weird. Needs investigating.
Changes
tags Milestone-0.10 Status-Accepted
Zash
on
As I wrote in #1211, the traceback is obscured by libevent.
It would be helpful to have debug logs from just before the traceback in order to understand how it happens.
Changes
tags Status-NeedInfo
MattJ
on
Debug logs (same server):
Nov 01 10:39:21 c2s255c6390 debug Received[c2s]: <iq id='purplef7ac842' type='get'>
Nov 01 10:39:21 mod_firewall::preroute debug chain "user/pass_acceptable" returned false
Nov 01 10:39:21 mod_firewall::deliver debug chain "user/spam_check_custom" returned nil
Nov 01 10:39:21 mod_firewall::deliver debug chain "user/spam_check" returned false
Nov 01 10:39:21 mod_firewall::deliver debug chain "user/pass_acceptable" returned false
Nov 01 10:39:21 c2s255c6390 debug Sending[c2s]: <iq id='purplef7ac842' type='result' to='[full jid of local user censored]'>
Nov 01 10:39:21 mod_bosh debug table: 0x1d1b9c40 was soon to timeout (at 1541064921, now 1541065164), sending empty response
Nov 01 10:39:21 general error Top-level error, please report:
/usr/lib/prosody/modules/mod_bosh.lua:483: attempt to index field '?' (a nil value)
Nov 01 10:39:21 general error
stack traceback:
[C]: in function 'loop'
/usr/lib/prosody/net/server_event.lua:751: in function </usr/lib/prosody/net/server_event.lua:750>
[C]: in function 'xpcall'
/usr/bin/prosody:400: in function 'loop'
/usr/bin/prosody:431: in main chunk
[C]: ?
Zash
on
Okay, that wasn't all that helpful unfortunately.
A request times out but there is no session attached?
I did not see any error including "mod_bosh.lua" in the last week on our server. The server runs on prosody-0.11 1nightly91-1~stretch. I think this issue can be closed. If it comes up again, I'll open a new issue. Thank you!
Oct 04 16:33:54 general error Top-level error, please report: /usr/lib/prosody/modules/mod_bosh.lua:483: attempt to index field '?' (a nil value) Oct 04 16:33:54 general error stack traceback: [C]: in function 'loop' /usr/lib/prosody/net/server_event.lua:751: in function </usr/lib/prosody/net/server_event.lua:750> [C]: in function 'xpcall' /usr/bin/prosody:400: in function 'loop' /usr/bin/prosody:431: in main chunk [C]: ? What version of the product are you using? On what operating system? Prosody 0.10 1nightly436-1~jessie on amd64 Debian Jessie Stable. Please provide any additional information below. Appeared a lot on prosody.err today.
Weird. Needs investigating.
ChangesAs I wrote in #1211, the traceback is obscured by libevent. It would be helpful to have debug logs from just before the traceback in order to understand how it happens.
ChangesDebug logs (same server): Nov 01 10:39:21 c2s255c6390 debug Received[c2s]: <iq id='purplef7ac842' type='get'> Nov 01 10:39:21 mod_firewall::preroute debug chain "user/pass_acceptable" returned false Nov 01 10:39:21 mod_firewall::deliver debug chain "user/spam_check_custom" returned nil Nov 01 10:39:21 mod_firewall::deliver debug chain "user/spam_check" returned false Nov 01 10:39:21 mod_firewall::deliver debug chain "user/pass_acceptable" returned false Nov 01 10:39:21 c2s255c6390 debug Sending[c2s]: <iq id='purplef7ac842' type='result' to='[full jid of local user censored]'> Nov 01 10:39:21 mod_bosh debug table: 0x1d1b9c40 was soon to timeout (at 1541064921, now 1541065164), sending empty response Nov 01 10:39:21 general error Top-level error, please report: /usr/lib/prosody/modules/mod_bosh.lua:483: attempt to index field '?' (a nil value) Nov 01 10:39:21 general error stack traceback: [C]: in function 'loop' /usr/lib/prosody/net/server_event.lua:751: in function </usr/lib/prosody/net/server_event.lua:750> [C]: in function 'xpcall' /usr/bin/prosody:400: in function 'loop' /usr/bin/prosody:431: in main chunk [C]: ?
Okay, that wasn't all that helpful unfortunately. A request times out but there is no session attached?
Timeout logic had a major overhaul in 0.11: https://hg.prosody.im/trunk/rev/118858bf47cd so maybe this issue went away with that.
Is this still an issue?
I did not see any error including "mod_bosh.lua" in the last week on our server. The server runs on prosody-0.11 1nightly91-1~stretch. I think this issue can be closed. If it comes up again, I'll open a new issue. Thank you!
Thanks! Let's hope this doesn't come back then.
Changes