Closed Dartellum closed 4 years ago
Yea, i think from your description debug log of that incident should be useful. It's likely an incomplete stream that gets dumped when you reconnect. The xml tag formatting can be somewhat random, there could be new lines or other tags where i don't expect them to be.
Somewhere in this mess (I parsed down from the last command that worked to right before I disconnected).
I ran your log through the message parser but didn't find anything out of the ordinary. Do you still remember what was the last message you saw on your screen?
Hello,
I have 4 other logs I can snip and send. They may have more details since all the sessions did the same thing.
Regards, Dr. Walter Wesley "Wes" Snyder V 484-300-4833 (h) 484-431-3355 (m)
On Mon, Dec 18, 2017 at 3:21 AM, matoom notifications@github.com wrote:
I ran your log through the message parser but didn't find anything out of the ordinary. Do you still remember what was the last message you saw on your screen?
— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/matoom/frostbite/issues/41#issuecomment-352355925, or mute the thread https://github.com/notifications/unsubscribe-auth/AU8XXM9AapCG9_zq5ngRj1O3-D9KNsOzks5tBiCOgaJpZM4REsLQ .
I do not have the debug logs turned on for the other ones I was running. :( So, I will need to let one run until it does it again.
It would be great if you could reproduce and capture the moment when it stops working. I've been running my client non-stop for at least a month or when ever i made the last release but one thing i haven't done is maintaining a connection for probably longer than 8hrs or so. I suppose most f2p's keep logged in most of the time.
Does it always happen at the same time/same activity? I'm thinking maybe there's some sort of a message dragonrealms puts out if you've been logged in for a long time?
I know it happened after 50 hours the one time and 80 some the other. I am 46.5 hours in this run. Let's see if it happens again.
Regards, Dr. Walter Wesley "Wes" Snyder V 484-300-4833 (h) 484-431-3355 (m)
On Mon, Dec 18, 2017 at 3:49 PM, matoom notifications@github.com wrote:
It would be great if you could reproduce and capture the moment when it stops working. I've been running my client non-stop for at least a month or when ever i made the last release but one thing i haven't done is maintaining a connection for probably longer than 8hrs or so. I suppose most f2p's keep logged in most of the time.
Does it always happen at the same time/same activity? I'm thinking maybe there's some sort of a message dragonrealms puts out if you've been long in for a long time?
— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/matoom/frostbite/issues/41#issuecomment-352553432, or mute the thread https://github.com/notifications/unsubscribe-auth/AU8XXFXwLexZIXaH0JabvAUwUsIC8FTZks5tBs_ggaJpZM4REsLQ .
This happens on my wife's characters. She can logout and log back in and no input will show output. This happened when they took magic offline for Trader magic.
This only happens to a Trader then? The description sounds a lot like an unfinished stream but without any hard data, there's no way i can just guess what's happening though.
I've been thinking of leaving a F2P character logged in for a while but will that produce this error? Is there anything else you're doing? Can i just afk in an isolated room and do nothing?
Not to a Trader.. wife is Empath. Let me grab the logs because since that update she also gets another error.
Main log: [21:32] You feel a surge of life energy as your regeneration pulses, the spell pattern manifesting as a cascade of rutilant sparks that bathe you in a warm glow. [21:32] The external scars to your right hand, neck and right arm are fully healed. [21:32] The internal scars to your right hand, neck, right arm, left leg, left arm and chest are fully healed. [21:32] > [21:32] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's left leg. [21:32] >nod tap [21:32] You nod to Tapmev. [21:32] > [21:32] Dokt rubs the back of his neck then stretches and returns to work. [21:32] > [21:32] [21:33] > l [21:33] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's right arm. [21:33] >e
Debug log:
AFK should leave a character in. You can run to Dokt's, ;go2 Dokt, and see if it stops working.
Yes, there it is. That "pushStream" gets thrown into the main.log, that's a problem.
[21:32] Dokt rubs the back of his neck then stretches and returns to work.
[21:32] >
[21:32] </pushStream>
[21:33] > l
From what you showed me everything seems fine but there could be something important missing from the time "Dokt rubs the back" and until the "l" command. The debug.log doesn't show that part entirely, is there anything going on in the debug log between those two events?
That is line for line of that section. I can submit the whole debug log (even a couple days' worth) showing where it does this at another location as well.
Regards, Dr. Walter Wesley "Wes" Snyder V 484-300-4833 (h) 484-431-3355 (m)
On Thu, Jan 18, 2018 at 4:52 PM, matoom notifications@github.com wrote:
Yes, there it is. That "pushStream" gets thrown into the main.log, that's a problem.
[21:32] Dokt rubs the back of his neck then stretches and returns to work. [21:32] > [21:32] [21:33] > l
From what you showed me everything seems fine but there could be something important missing from the time "Dokt rubs the back" and until the "l" command. The debug.log doesn't show that part entirely, is there anything going on in the debug log between those two events?
— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/matoom/frostbite/issues/41#issuecomment-358793540, or mute the thread https://github.com/notifications/unsubscribe-auth/AU8XXBy0MkuBll6FsuouZy0Z8kMPE-f5ks5tL70FgaJpZM4REsLQ .
Wait but, the "Dokt rubs the back of his neck then" isn't shown in the debug log though? It should be there.
"Dokt rubs the back" is the last message that was successfully printed and by the time you see "pushStream" in the main.log it's already over, it's frozen. It's probably caused by something that happened between those two lines.
Can you see the other three logs? I provided one character main and debug logs and a second character the same. I cut and pasted a bit above to where I had to disconnect.
Regards, Dr. Walter Wesley "Wes" Snyder V 484-300-4833 (h) 484-431-3355 (m)
On Thu, Jan 18, 2018 at 5:07 PM, matoom notifications@github.com wrote:
"Dokt rubs the back" is the last message that was successfully printed by the time you see "pushStream" in the main.log it's already over, it's frozen. It's probably caused by something that happened between those two lines.
— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/matoom/frostbite/issues/41#issuecomment-358797843, or mute the thread https://github.com/notifications/unsubscribe-auth/AU8XXH0EfLkWpknjkMnhLZV4mAyVGXiOks5tL8C3gaJpZM4REsLQ .
No, i see the logs. From what you posted. As you can see the debug log starts with a <popStream/>
. What interests me is what happens before that.
Main log:
[21:32] You feel a surge of life energy as your regeneration pulses, the spell pattern manifesting as a cascade of rutilant sparks that bathe you in a warm glow.
[21:32] The external scars to your right hand, neck and right arm are fully healed.
[21:32] The internal scars to your right hand, neck, right arm, left leg, left arm and chest are fully healed.
[21:32] >
[21:32] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's left leg.
[21:32] >nod tap
[21:32] You nod to Tapmev.
[21:32] >
*** [21:32] Dokt rubs the back of his neck then stretches and returns to work.
[21:32] >
[21:32] </pushStream>
[21:33] > l
[21:33] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's right arm.
[21:33] >e
Debug log:
<popStream/>
<pushStream id="thoughts"/>[DRPrime]-DR:Zelaskl: "well hz is a list of hunting zones"
<popStream/>
<dialogData id='minivitals'><skin id='manaSkin' name='manaBar' controls='mana' left='20%' top='0%' width='20%' height='100%'/><progressBar id='mana' value='94' text='mana 94%' left='20%' customText='t' top='0%' width='20%' height='100%'/></dialogData>
<c> l
main log: [21:31]` Tapmev ponders. [21:31] > [21:31] You sense that Tapmev's external right arm and right hand scars are fully healed. [21:31] You sense that Tapmev's internal right arm, left arm, right hand and chest scars are fully healed. [21:31] > [21:31] You sense that your system has fully recovered from your recent manifestation of the unity link. [21:31] >grin [21:31] You decide against disturbing the quiet by fidgeting. [21:31] > [21:31] You feel a surge of life energy as your regeneration pulses, the spell pattern manifesting as a cascade of rutilant sparks that bathe you in a warm glow. [21:31] The external wounds to your right hand, neck and right arm feel better. [21:31] The internal wounds to your chest, left leg, neck, left arm, right hand and right arm feel better. [21:31] The external scars to your neck, right hand and right arm feel better. [21:31] The internal scars to your chest, right arm, right hand, left arm, neck and left leg feel better. [21:31] The external scars to your left arm, left leg, right leg, left hand and abdomen are fully healed. [21:31] The internal scars to your right leg, skin, abdomen and left hand are fully healed. [21:31] > [21:31] You feel fully rested. [21:31] > [21:31] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's chest. [21:31] >wave [21:31] Tapmev touches you. [21:31] You feel a warmth radiate from Tapmev's touch. [21:31] You have a brief sensation that leaves your wounds tingling. [21:31] > [21:31] You wave. [21:31] > [21:31] Tapmev winks. [21:31] >poke tap [21:31] You decide against disturbing the silence of the area. [21:31] > [21:31] Dokt rubs the back of his neck then stretches and returns to work. [21:31] >shrug [21:31] You shrug. [21:31] >giggle [21:31] You giggle. [21:32] >l tap [21:32] Tapmev nods. [21:32] > [21:32] You see Tapmev, Initiate Empath, a Dwarf. [21:32] Tapmev has a square face, silver eyes and a pinched nose. He has cropped light brown hair, with rosy skin and a hefty build. [21:32] He is tiny for a Dwarf. [21:32] He appears to be young. [21:32] He has a thick bushy mustache that droops heavily on his upper lip and a long shaggy beard. [21:32] He is in good shape. [21:32] [21:32] He is wearing a braided cambrinth armband, some rugged gloves, a rugged leather cowl, a divine charm, a sturdy backpack embroidered with a ribbon-clad box, an old flannel tunic, some rugged leathers emblazoned with a scratched crest, some silver-chased elbow spikes, a cooking pot lid, a small steel skinning knife with a leather-wrapped hilt, an etched steel parry stick with black leather straps, a pair of dusky steel hand claws fashioned to look like a wyvern's, a pair of copper zills, a grey cambrinth ring, a red gem pouch and some brown goatskin pants. [21:32] > [21:32] You feel a surge of life energy as your regeneration pulses, the spell pattern manifesting as a cascade of rutilant sparks that bathe you in a warm glow. [21:32] The external wounds to your right hand feel better. [21:32] The internal wounds to your right arm, left arm, right hand and chest feel better. [21:32] The external scars to your right hand, neck and right arm feel better. [21:32] The internal scars to your right arm, right hand, neck, left arm, left leg and chest feel better. [21:32] > [21:32] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's left leg. [21:32] > [21:32] Dokt rubs the back of his neck then stretches and returns to work. [21:32] >nudge tap [21:32] You elbow Tapmev in the ribs in a playful sort of way. [21:32] >l [21:32] [Knife Clan, Triage] [21:32] Low groans rising from the injured fill the cramped alcove as wounded hunters and warriors receive aid. Mattresses line the walls, about half occupied by recovering patients. The floor is almost entirely covered by a dark red stain, paying testament to the enthusiasm of the field surgeons. An ominous bone saw leans against the wall, obviously a favorite method of treatment. You also see a burly tanned pig with a few things on it and the surgeon Dokt. [21:32] Also here: Darkling Oliviia who is darkened by an unnatural shadow, Guerilla Charok who is sitting, Odoyle who is lying down and Tapmev. [21:32] Obvious exits: east. [21:32] Room Number: 6218 [21:32] > [21:32] Tapmev just touched Oliviia. [21:32] >point oli [21:32] You point at Oliviia. [21:32] > [21:32] [sanowret-crystal]>gaze my sanowret crystal [21:32] This is not a good place for that. [21:32] > [21:32] You feel a surge of life energy as your regeneration pulses, the spell pattern manifesting as a cascade of rutilant sparks that bathe you in a warm glow. [21:32] The external scars to your right hand, neck and right arm are fully healed. [21:32] The internal scars to your right hand, neck, right arm, left leg, left arm and chest are fully healed. [21:32] > [21:32] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's left leg. [21:32] >nod tap [21:32] You nod to Tapmev. [21:32] > [21:32] Dokt rubs the back of his neck then stretches and returns to work. [21:32] > [21:32] [21:33] > l [21:33] The surgeon Dokt whistles between his teeth as he rather roughly rubs some ointment on Odoyle's right arm. [21:33] >e
debug log: Tapmev ponders.
You sense that Tapmev's external right arm and right hand scars are fully healed.
You sense that Tapmev's internal right arm, left arm, right hand and chest scars are fully healed.
You sense that your system has fully recovered from your recent manifestation of the unity link.
Ok, thanks. It's all there now but i'm afraid at first glance it did not produce the same error when i ran it.
You are running Lich?
Regards, Dr. Walter Wesley "Wes" Snyder V 484-300-4833 (h) 484-431-3355 (m)
On Thu, Jan 18, 2018 at 5:34 PM, matoom notifications@github.com wrote:
Ok, thanks. It's all there now but i'm afraid at first glance it did not produce the same error when i ran it.
— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/matoom/frostbite/issues/41#issuecomment-358804237, or mute the thread https://github.com/notifications/unsubscribe-auth/AU8XXA8hv3qumpZo5Tvc_xAdug4zztqfks5tL8bngaJpZM4REsLQ .
No, i just ran the log that you just pasted through the xml parser locally but it doesn't guarantee the same results, that's for sure.
Looking at the log this part actually seems a little odd to me. There's two streams getting merged probably because the moonWindow event occurs at the same exact time as the percWindow event. I'm not entirely convinced but it's likely the client can't handle this. I'll have to see if it's causing the issue and if it's possible to fix.
<pushStream id="percWindow"/>Regenerate (Indefinite)
<clearStream id="moonWindow"/>
<pushStream id="moonWindow"/> [k]-(26) [y]-(53) [x]-(171)<popStream/>
<popStream/><prompt time="1516242754">></prompt>
My initial assessment was wrong. The stream stacking that occurs with the moon window is still an unsalvageable error but it won't crash the client. It will only cause faulty data to be shown in client (probably ends up in the perc window instead). There's not much i can do about that. They should properly synchronize streams in Lich to prevent that from happening but i doubt it's easily accomplished.
I looked into the Lich side of things and you were right to point out the broken pipe error. I believe that's the best indication of what is happening. Looking at the logs the last two commands "l" and "e" are not going anywhere (no response from server).
On the client side there's no indication of any errors. The broken pipe error is coming from Lich and it's fairly well known as "the tcp client did not know the connection was dropped error". My best guess is that it was killed by a firewall or on the server side. It's not uncommon for firewalls to kill connections that have been running for a very long time or the connection could have easily been dropped by a faulty network device. etc.
This is where the error originates in Lich but it's a pretty standard network error (for the sake of the error message "Lich: error: client_thread").
Lich.rb: 7158
if $_DETACHABLE_CLIENT_
begin
$_DETACHABLE_CLIENT_.write(alt_string)
rescue
$_DETACHABLE_CLIENT_.close rescue nil
$_DETACHABLE_CLIENT_ = nil
respond "--- Lich: error: client_thread: #{$!}"
respond $!.backtrace.first
Lich.log "error: client_thread: #{$!}\n\t#{$!.backtrace.join("\n\t")}"
end
end
As it first started occurring when trader magic was released i wouldn't rule out any crashes on the server side but something you could try is if you're running the lich frostbite integration then try running a separate lich instance instead (in case the subprocess is somehow getting throttled by the op system or something along those lines, although unlikely).
I found after 50 to 80 hours of continuous use all inputs stop. Scripts are running but my guy is standing there. After a few commands, no commands return. The only way to correct is to disconnect and connect again. I do have debug logging on, if that helps.