Register forum user name Search FAQ

Gammon Forum

Notice: Any messages purporting to come from this site telling you that your password has expired, or that you need to verify your details, confirm your email, resolve issues, making threats, or asking for money, are spam. We do not email users with any such messages. If you have lost your password you can obtain a new one by using the password reset link.

Due to spam on this forum, all posts now need moderator approval.

 Entire forum ➜ MUSHclient ➜ General ➜ Double lines in log files

Double lines in log files

It is now over 60 days since the last post. This thread is closed.     Refresh page


Posted by forral   USA  (79 posts)  Bio
Date Wed 12 Jan 2011 03:10 AM (UTC)

Amended on Wed 12 Jan 2011 03:19 AM (UTC) by forral

Message
Hi,

I've been logging rather extensively lately and have noticed a small issue...

In most of my recent logs, when I am in a formation and following behind someone, the log starts capturing double lines of almost everything.

If we are standing still, it is fine, but as soon as I "follow" X person it'll double up my lines.

I'll post snapshots to clarify my point since its hard to verbalize.

I'm using v4.64 of MUSH and will upgrade as soon as I can to try and see if maybe this is a bug that was fixed.

Snapshots as follows
http://i55.tinypic.com/2w7hmxl.jpg

http://i55.tinypic.com/35bihck.jpg


Thanks for any help,
Forral

*Edit* sorry the pics are crappy quality I resized them for 1024x728 but you can still discern the problem.
Top

Posted by Nick Gammon   Australia  (23,133 posts)  Bio   Forum Administrator
Date Reply #1 on Wed 12 Jan 2011 06:36 AM (UTC)
Message
I couldn't see log lines in those images. Anyway, quite possibly you have a trigger that is logging something.

Template:bug

Please provide a summary of your world configuration:

  • Either use the scripting Immediate window (Ctrl+I) to execute: Debug ("summary")

    or

  • Install the Summary plugin (see "Summary" feature) and type "summary"

Then copy the resulting information from the output window, and paste into a Forum message.

You need version 4.55 onwards of MUSHclient to do this.



- Nick Gammon

www.gammon.com.au, www.mushclient.com
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #2 on Thu 13 Jan 2011 09:33 PM (UTC)
Message
As requested:

-------------- MUSHclient summary --------------

MUSHclient version: 4.64
Compiled: Oct 6 2010.
Time now: Thursday, January 13, 2011, 4:31 PM
Operating system: Windows 7
Libraries: Lua 5.1.4, PCRE 8.10, PNG 1.4.3, SQLite3 3.7.2, Zlib 1.2.5
World name: 'Materia Magica - Forral', ID: 304a00c3b02ca596b663e30f
-- Scripting --
Script language: Lua, enabled: yes
Scripting active: yes
Lua sandbox is 127 characters, DLL loading allowed: yes
Scripting prefix: ''. External editor in use: NO.
Scripting for: 5.929820 seconds.
-- Triggers, aliases, timers, variables --
** Triggers: 201 in world file, triggers enabled: yes. [Triggers]
191 enabled, 6 regexp, 2106175 attempts, 11 matched, 2.105132 seconds.
** Aliases: 259 in world file, aliases enabled: yes. [Aliases]
258 enabled, 0 regexp, 90117 attempts, 106 matched, 0.061087 seconds.
** Timers: 2 in world file, timers enabled: NO. [Timers]
2 enabled, 0 fired.
Timers checked every 0.1 seconds.
** Variables: 20. [Variables]
-- MCCP --
MCCP active, took 0.025798 seconds to decompress
MCCP received 85493 compressed bytes, decompressed to 729148 bytes.
MCCP compression ratio was: 11.7% (lower is better)
-- Plugins (Processing order) --
ID: ba1329d6355e728dd100fd41, 'Calculator', Enabled [Al]
ID: 1b539fcdb672e5aafeddc6a5, 'CombatTimer', Enabled [Tr Va]
ID: 7210c1be34cf14cda8ae48c4, 'KillsTillNextLevel', Enabled [Tr]
ID: dc109a6028175ae32994c200, 'MM_Auto_Sac', Enabled [Tr Al Va Cb]
ID: 92b57df297cc20bc996ae62e, 'MM_Item_Commentor', Enabled [Tr Al Va Cb]
ID: dae253fbfaef31eea738b928, 'MM_Tells_Highlighter', Enabled [Tr]
ID: 0f2eed7474810fdca5cd293c, 'MMChatCapture', Enabled [Tr]
ID: c5d8d8afc978a30bddebdd83, 'MMHitCounter', Enabled [Tr Al Va]
ID: 48b15011a99fe101d0f9b5e3, 'ShipKeypad', Enabled [Al]
ID: c1e3dc8f443468463956cd88, 'Silkbags', Enabled [Tr Al]
** Plugins: 10 loaded, 10 enabled.
-- Comms --
Connect phase: 8 (Open). NAWS wanted: NO
Received: 89746 bytes (87 Kb)
Sent: 21946 bytes (21 Kb)
Received 1118 packets, sent 758 packets.
Total lines received: 11373
This connection: Sent 752 lines, received 13693 lines.
Telnet (IAC) received: DO: 0, DONT: 0, WILL: 7, WONT: 1, SB: 2 [Telnet]
-- MXP --
MXP active: yes, Pueblo mode: NO, Activated: On command
MXP tags received: 0
MXP entities received: 0
MXP errors: 0
-- Commands --
Commands in command history: 255
Speed walking enabled: yes. Speed walking prefix: #
Command stacking enabled: yes. Command stack character: ';'
Accelerators defined: 0
-- Miniwindows --
** Miniwindows: 0 loaded, 0 shown.
-- Output window --
Output pixels: width 1579, height: 659, font width: 7, font height: 14
can show 225 characters, wrapping at column 108, height 47 lines.
Output buffer: 11391 of 500000 lines.
-- Miscellaneous --
Logging: NO, tracing: NO
** SQLite3 databases: 0
Sound buffers in use: 0

---------------------- End summary ----------------------


I can re-take the screenshots for you in a higher resolution if you like, Nick.
Top

Posted by Nick Gammon   Australia  (23,133 posts)  Bio   Forum Administrator
Date Reply #3 on Thu 13 Jan 2011 10:39 PM (UTC)
Message
I honestly can't see anything in the log part of the screen shot. It just looks white.

But you don't need to take screen shots, just copy the text and paste it - that is much more bandwidth-friendly.

Also I would try with plugins disabled. I can't help wondering if a plugin somewhere is choosing to log stuff. However if not, then that at least disproves that theory, if it still does it when all plugins are disabled.

- Nick Gammon

www.gammon.com.au, www.mushclient.com
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #4 on Fri 14 Jan 2011 01:20 AM (UTC)
Message
Normal text:

Lair of the Dracolich - - -
(-------------------------------------------------) - <---(M)-D-> -
WARNING: You are in a CHAOTIC PLAYER KILLING room. - - -

The is a strange howling in the air, and you scan the cave a giant pile
of bones can be seen to one corner. A strange pair of red orbs stare
strangely at you, deep within the pile of bones. You can sense the aura of
a great and powerful evil lurking within. The floor is smooth and flat,
almost to an icy texture, broken shields, and charred remains of some
humanoids liter the floor before you.
The corpse of the Dracolich is lying here.
A wooden-handled fly whisk is here.
(Red Aura) A summoning pentagram has been drawn on the ground with chalk.
An opalescent scroll has been dropped here. (MAGIC BOMB)
[Clan 152] (Gold Aura) *Ezekail, Destroyer Slayer. is here, mounted on a young pegasus, fighting the
Dracolich. [93%]
[Clan 152] (Red Aura) **Brimstone i beat clan 30 and all i got was this title is here, fighting the
Dracolich. [94%]
(Pink Aura) **The Dracolich is here, fighting Ezekail. [77%]
The Dracolich has several minor wounds and bruises.

([91%]Ezekail)[AWAY]<3120hp 1910sp 1607st>
Brimstone has completed his casting.
A warm, blue aura surrounds Ezekail for a moment, then softly fades.
The Dracolich has some significant wounds.


Doubled up text:

([86%]Ezekail)[AWAY]<2632hp 1451sp 1519st>
Lair of the Dracolich - - -
(-------------------------------------------------) - <---(M)-D-> -
(-------------------------------------------------) - <---(M)-D-> -
WARNING: You are in a CHAOTIC PLAYER KILLING room. - - -

The is a strange howling in the air, and you scan the cave a giant pile
of bones can be seen to one corner. A strange pair of red orbs stare
strangely at you, deep within the pile of bones. You can sense the aura of
a great and powerful evil lurking within. The floor is smooth and flat,
almost to an icy texture, broken shields, and charred remains of some
humanoids liter the floor before you.
The corpse of the Dracolich is lying here.
The corpse of the Dracolich is lying here.
A wooden-handled fly whisk is here.
A wooden-handled fly whisk is here.
(Red Aura) A summoning pentagram has been drawn on the ground with chalk.
(Red Aura) A summoning pentagram has been drawn on the ground with chalk.
An opalescent scroll has been dropped here.
An opalescent scroll has been dropped here. (MAGIC BOMB)
[Clan 152] (Gold Aura) *Ezekail, Destroyer Slayer. is here, mounted on a young pegasus, fighting the
Dracolich. [86%]
[Clan 152] (Red Aura) **Brimstone i beat clan 30 and all i got was this title is here, fighting the
Dracolich. [87%]
(Pink Aura) *The Dracolich is here, fighting Ezekail. [49%]
(Pink Aura) *The Dracolich is here, fighting Ezekail. [49%]
The Dracolich has some very significant wounds and scratches.

([82%]Ezekail)[AWAY]<2632hp 1417sp 1508st> in
in
You are carrying:
an opalescent scroll (new)
an opalescent scroll (new) (MAGIC BOMB)
a scale of the Dracolich
a scale of the Dracolich
the end bit of a massive, complex key
the end bit of a massive, complex key
( 2) a non-existent payroll stub with a scribbled '100,000' at the bottom
( 2) a non-existent payroll stub with a scribbled '100,000' at the bottom
a secure bag (new)
a secure bag (new)
a rotting titanskull (fresh)
a rotting titanskull (fresh)
(Gold Aura) an alchemical alembic (new)
(Gold Aura) an alchemical alembic (new)
a blue silk bag (new)
a blue silk bag (new)
( 2) Vandemaar's Trunk (new)
( 2) Vandemaar's Trunk (new)
a black silk bag (new)
a black silk bag (new)
a red silk bag (new)
a red silk bag (new)
A total of 13 items weighing 11 stones, 73 pebbles (4pb gold weight)
You may carry a maximum of 55 items weighing up to 25 st, 0 pb.
The Dracolich has some very significant wounds and scratches.




2 notes I want to make:
1) I have no idea why the hell the log is doing that. During that fight, nothing was changed, no plugins removed/disabled/added. In fact, that is from the same log, and the beginning of the log is perfect, even in a form.

2) This may be more significant, but the colors change midway through the log. They go from the BRIGHT version that the log normally uses, to a very dull shade halfway through the log. And -that- is when the lines start doubling up. Any ideas what may be causing this Nick?

I have a picture (clear, this time) of the color change if you're interested, however you expressed concerns about bandwidth so I won't post it right now. The change occurs about a quarter way through the logfile.
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #5 on Fri 14 Jan 2011 01:24 AM (UTC)
Message
Just adding one more thing, I've looked through some solo logs, and I don't seem to encounter this problem. Its only when I'm doing a run(raid, dungeon, whatever you guys want to call it) that I'm encountering this problem, when I'm in a formation with another player.

In the solo logs, the colors are not changing, nor do I have the double lines problem.

I am updating my MUSH install as we speak.
Top

Posted by Nick Gammon   Australia  (23,133 posts)  Bio   Forum Administrator
Date Reply #6 on Fri 14 Jan 2011 02:32 AM (UTC)
Message
You know, this sounds like a server error. You say it works fine until you follow another player. Or did you say that the screen looks fine but not the log?

Here's another idea. One of your plugins may be omitting from output but not omitting from the log. And then it may be re-displaying using a Note.

For example from your pasted log:


An opalescent scroll has been dropped here.
An opalescent scroll has been dropped here. (MAGIC BOMB)


Now those lines aren't identical. It looks to me like (assuming you only see one on the screen) that a plugin (or your main world file triggers) are omitting things, and then redisplaying them. However if both are logged they will be doubled up in the logs.

Here's another possibility. You seem to have quite a few plugins. Is it possible one is capturing stuff (eg. your inventory) and stopping when it gets your prompt (like one of my inventory plugins does)?

Now if a plugin normally looks for something like:


<3120hp 1910sp 1607st> 


but now that your are in formation (I am guessing) with Ezekail, then your prompt now is:


([91%]Ezekail)[AWAY]<3120hp 1910sp 1607st> 


So the plugin that tries to detect your prompt might be failing, and causing this problem.

- Nick Gammon

www.gammon.com.au, www.mushclient.com
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #7 on Fri 14 Jan 2011 02:55 AM (UTC)
Message
Nick Gammon said:

You know, this sounds like a server error. You say it works fine until you follow another player. Or did you say that the screen looks fine but not the log?

Here's another idea. One of your plugins may be omitting from output but not omitting from the log. And then it may be re-displaying using a Note.

For example from your pasted log:


An opalescent scroll has been dropped here.
An opalescent scroll has been dropped here. (MAGIC BOMB)


Now those lines aren't identical. It looks to me like (assuming you only see one on the screen) that a plugin (or your main world file triggers) are omitting things, and then redisplaying them. However if both are logged they will be doubled up in the logs.

Here's another possibility. You seem to have quite a few plugins. Is it possible one is capturing stuff (eg. your inventory) and stopping when it gets your prompt (like one of my inventory plugins does)?

Now if a plugin normally looks for something like:


<3120hp 1910sp 1607st> 


but now that your are in formation (I am guessing) with Ezekail, then your prompt now is:


([91%]Ezekail)[AWAY]<3120hp 1910sp 1607st> 


So the plugin that tries to detect your prompt might be failing, and causing this problem.


Its odd, it seems to be doing it at certain times, and not others. That line you saw with the scroll and the name in parentheses, that is a scroll identifier, so maybe that is causing the issue. I -do- have a plugin that captures my prompt but it is a miniwindow and I don't have it enabled atm, nor do I remember it causing any issues. Its hard to know exactly which plugin is causing the issue because sometimes the issue is replicated, other times it is not. I'm guessing it -may- be that scroll identifier plugin I have.

Thanks for your help Nick, I'll try and debug this and report back any problems.
Top

Posted by Nick Gammon   Australia  (23,133 posts)  Bio   Forum Administrator
Date Reply #8 on Fri 14 Jan 2011 05:32 AM (UTC)
Message
Try turning on the Trace feature (Game menu from memory). Then you see if triggers etc. are matching. You might see different behaviour from when it works to when it goes all strange.

- Nick Gammon

www.gammon.com.au, www.mushclient.com
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #9 on Fri 14 Jan 2011 08:09 PM (UTC)
Message
Nick Gammon said:

Try turning on the Trace feature (Game menu from memory). Then you see if triggers etc. are matching. You might see different behaviour from when it works to when it goes all strange.


Good idea, I'll form up with someone and try that. I think it may be that scroll identifier plugin though. Thanks :)
Top

Posted by forral   USA  (79 posts)  Bio
Date Reply #10 on Fri 14 Jan 2011 09:31 PM (UTC)

Amended on Fri 14 Jan 2011 09:36 PM (UTC) by forral

Message
As it turns out it was indeed my item commentor plugin that was causing the issue. I tested it by having a form member take a scroll out in his inventory, and checked the log before and after. The colors changed and the text started doubling up immediately after the scroll.

Thanks Nick for your help.



Decara Healer's Office - - -
TRACE: Matched trigger littlemutli40
TRACE: Executing trigger script "item_comment"
(-------------------------------------------------) # <---(M)---> -
TRACE: Matched trigger little40
TRACE: Executing trigger script "item_comment"
- - -

While this building isn't very large, it is obviously well-used. The
interior is designed to make it seem like there is a lot of open space in
the room, and you feel very relaxed just being here. There are a couple
couches to rest on, and an examining table where treatments can be
performed.
TRACE: Matched trigger little40
TRACE: Executing trigger script "item_comment"
A stone couch that is tailored to the contours of your body is here in the corner.
[Clan 139] (Red Aura) *Magistra Alyce in Wonderland is here, mounted on an okapi.
TRACE: Matched trigger littlemutli40
TRACE: Executing trigger script "item_comment"
(Red Aura) *[Shop] An ogre healer is here, willing to treat your wounds.
TRACE: Matched trigger littlemutli40
TRACE: Executing trigger script "item_comment"
(Red Aura) An ogre draped in battle armor huddles in the corner, breathing deeply.

[*][SAFE]<3440hp 1938sp 1679st>
Alyce has completed her casting.

[*][SAFE]<3440hp 1938sp 1679st>
Alyce stops using A copy of Lewis Carrol's "Through the Looking Glass".

[*][SAFE]<3440hp 1938sp 1679st>
Alyce puts A copy of Lewis Carrol's "Through the Looking Glass" in a magical belt pouch.

[*][SAFE]<3440hp 1938sp 1679st>
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce takes a damp coquelicot scroll from Vandemaar's Trunk.
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce takes a smoky transparent scroll from Vandemaar's Trunk. (SLEEP)
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce takes an unpleasant-smelling silver scroll from Vandemaar's Trunk. (FARSIGHT)
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce takes a scroll made of rattlesnake skin from Vandemaar's Trunk.

[*][SAFE]<3452hp 1993sp 1795st>
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce puts a scroll made of rattlesnake skin in Vandemaar's Trunk.
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce puts an unpleasant-smelling silver scroll in Vandemaar's Trunk. (FARSIGHT)
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce puts a smoky transparent scroll in Vandemaar's Trunk. (SLEEP)
TRACE: Matched trigger SCROLL10
TRACE: Executing trigger script "item_comment"
Alyce puts a damp coquelicot scroll in Vandemaar's Trunk.

[*][SAFE]<3452hp 1993sp 1795st> ahh
You go AaaHhHHhhh.

[*][SAFE]<3452hp 1993sp 1795st> ft yup
You tell the formation '-Forral- yup'
TRACE: Matched trigger "^(.*?)You tell (the formation|([A-Za-z]+))\,? \'(.*?)\'$"
TRACE: Executing trigger script "redirect"


It was indeed commenting to all the lines that I was experiencing the double-up in.
Top

The dates and times for posts above are shown in Universal Co-ordinated Time (UTC).

To show them in your local time you can join the forum, and then set the 'time correction' field in your profile to the number of hours difference between your location and UTC time.


28,241 views.

It is now over 60 days since the last post. This thread is closed.     Refresh page

Go to topic:           Search the forum


[Go to top] top

Information and images on this site are licensed under the Creative Commons Attribution 3.0 Australia License unless stated otherwise.