Bug - Not A Bug Strange "no action" bug in mafia

After some update, somewhere during first or second ToT day, I started to receive strange Mafia behavior. In first round I am receiving "You twiddle your thumbs." message, as if I trying to do something outside combat. I thought that it's exclusive ToT bug, but actually it's keep repeating even now, after end of Halloween.

CLI looks like that in that cases:
[34203] The Haunted Library
Encounter: bookbat
Round 0: Smiling Spectre wins initiative!
Round 1: Stomping Boots rubs its soles together, then stomps in place restlessly. Clearly, the violence it's done so far is only making it ache for some quality stomping.
KoLmafia thinks it is round 2 but KoL thinks it is round 1
Round 2: Smiling Spectre tries to steal an item!
KoLmafia thinks it is round 3 but KoL thinks it is round 2
You acquire an item: tattered scrap of paper
In relay browser I don't see any messages of round 1, of course. It's "twiddle" message instead it.

Unlike CLI, log haven't any messages about this, so I cannot say how frequently this situation is, but today I seen it twice already in 30 turns.

What can be reason of it?
 

Veracity

Developer
Staff member
I would say that you clicked somewhere outside of combat and KoL wouldn't let you go there.
 
It's definitely not "clicked" case. I am quite sure what am I clicking. Especially each 15 turns or so. Especially after 2 years of using Mafia when I didn't see it at all (except real "clicking" cases, of course. :)

Speaking about scripts, I am using only libramBurn.ash after combat. And it worked before last Trick or Treating without problems. It could be different now, of course... Especially because it was very frequent in the start of the day, but almost non-existant now, and it's quite corellating... Still it was not match with any obvous Libram actions, and, well, it worked before! Ok, I will try to disable it tomorrow and will tell about results. Thank you for the lead.
 
It's not the script. Just got this "twiddle" again on r13063 without any scripts on.

---addition---
But I must say that it was only once for first 100 turns. Unlike yesterday when with script on it was about every 15-20 turns. Ok, seems, script is the main reason of it. As I cannot decipher exact conditions, seems, it worth to close the case. :(
 
Last edited:

Veracity

Developer
Staff member
How exactly are you adventuring and getting into these fights?

- In the Relay Browser; you click on an adventure zone and when a monster appears, the first thing you see is "you twiddle your thumbs".
- Automation via the GUI.
- Automation via a script.

In any case, unless and until you attach a DEBUG log that includes a "you twiddle your thumbs" message from KoL, we can't even begin to conjecture what extra request was made after we submitted the first fight.php request. A DEBUG log, on the other hand, well tell us exactly what happened.
 

Bale

Minion
This happens to me when I am using the CAB and not when I disable the CAB. Steps to reproduce, sometimes.

  1. Enable CAB
  2. Get into a fight in the relay browser
  3. Click either the left-most CAB "again" button or else click the "adventure again" link at the bottom.
  4. Once in a while the fight in the relay browser will begin with "You twiddle your thumbs."

I've been seeing this for a few days. It is not common so it doesn't trouble me much and I haven't noticed a pattern to the malfunction. Since I might have to run quite a few battles before I encounter it I haven't bothered to report it or get a DEBUG log.
 
It was exactly as with Bale, Veracity. Only without CAB. :) Pressing "Adventure again" in relay browser leaded to this "twiddle" message. Log is not registering anything, and automating works good (well, I doubt that I could notice it anyway, as log doesn't contain anything).

But it was much easier yesterday, and I never seen it today. So either newest Mafia self-fixed it, or it was server-side issue. :/
 

Veracity

Developer
Staff member
I never use the CAB. I often press "Adventure again". I never get a twiddle message.

Nothing has changed in KoLmafia. You will have to provide a DEBUG log showing it happening to you before we can even begin to investigate this.
 

Veracity

Developer
Staff member
It should not be that, although libramBurn is a before battle script, I believe.

Consider concurrent requests in KoLmafia:

The GUI - "automation" - submits requests and waits for responses. While it is idle, it is green; UI controls are active, and you can tell it to execute actions. While it is busy, it is red, and UI controls are supposed to be disabled. When it is ready to accept user input again, it enables UI controls and goes green or red, depending on whether the last action succeeded or failed. In either case, it is ready for more work.

The gCLI is part of the GUI and can be used to run commands or scripts - more "automation" - but is not disabled while this is happening. That is why you can type more commands and they get QUEUED. But, while a CLI command or script is running, the rest of the GUI is supposed to be disabled, preventing multiple threads of "automation" from happening at once.

The Relay Browser is its own thing. Browsers can make multiple requests to KoL without waiting for earlier ones to complete. We see this most commonly for downloading multiple images at once, but you can start a new adventure while the Browser is waiting for a previous charpane refresh to happen, for example. Not to mention chat, which can happen while anything else is happening.

Now, while you are adventuring in the Relay Browser, we do not lock out the GUI. There is nothing to stop you from attempting automation, either by clicking on UI buttons in the GUI or by executing CLI commands. I vaguely remember another Feature Request (or perhaps just a discussion) suggesting that the GUI & CLI be locked while you are adventuring, but I think that is extremely unlikely to happen.

What I did do was to lock out some kinds of requests - use item, equip, familiar change - to fail without submitting a request if we know that you are in a fight or choice adventure. Perhaps we don't do that for use skill requests. We probably should.

There is another open bug report in which somebody caused themself a lot of trouble by executing an outfit change - apparently via a maximizer command - and then impatiently going to the Relay Browser and ending up in a non-abortable choice adventure.

What we have here, however, is a between-battle-script - libramBurn - and Relay Browser adventuring. Yes, you can set preferences to recover HP, maintain MP (including mana burning), maintain moods, and run betweenBattleScript before manual adventuring. We do that in the thread that handles a Relay request by recognizing that the request constitutes an "adventure" and proceeding to do all those other actions before we submit the adventuring request to KoL. In other words, it really should not be a case of simultaneous requests; a "long running between battle script" will just run for a long time and THEN we will submit the request.

Now, if you get impatient in your Browser and click "Adventure Again" a second time while the previous request executes the between battle actions, we will notice that recovery is still happening and will skip that and just submit the request. And in that case, we will have simultaneous requests going on, and when the first thread finishes and submits the request, you will certainly see a "twiddle your thumbs" sort of thing.

In summary, I see two possibilities here:

- The user is executing something in the gCLI while also adventuring in the Relay Browser
- The user really did click Adventure Again (or the equivalent button on the CAB) twice and submitted two requests.

My response to that is probably "don't do that", in which case, this becomes a Won't Fix. However, until and unless we get a DEBUG log - preferably one with the "Verbosely log communication between KoLmafia and browser" checked in General/Extra Debugging - there is nothing we can say about what really happened.
 

Veracity

Developer
Staff member
Let me summarize what I said in that last note. The observed behavior is consistent with this:

Code:
X Browser submits adventure request to KoLmafia
|+ KoLmafia starts running between battle script
||
||X Browser submits adventure request to KoLmafia
||| (KoLmafia notices that recovery is underway)
||X KoLmafia submits adventure request to KoL
||
|+ KoLmafia finishes running between battle script
X Kolmafia submits adventure request to KoL
The longer the between battle script takes, the more opportunity there is for the browser to submit the second request before the first one was actually submitted. It is not possible for KoLmafia to make the browser submit requests, so this can be either the user clicking a second time or, presumably, a Greasemonkey script.

This explains why the OP saw this happen more often when he was running libramBurn - a "long running" between battle script.

Now, as I said, my response to double clicking is to say "don't do that". I suppose that if we notice that recovery is already happening, we could delay the second request until the first one completes, rather than submitting it right away - but the user will not like the result of that any better, I expect; the request will still be submitted twice.

And I don't see how we can stop that. If the browser sends a request to KoLmafia to forward through to KoL, then, by golly, we will forward it through. It is not up to us to decide "you didn't mean to ask for that page, Browser."

I still want a debug log, so we can nail down exactly which requests got sent when and understand this particular report completely.
 
Sorry, Veracity. I enabled logging, but it not happened for me even once yesterday and today. With exactly the same scripts and equip and zone (Library). So it seems, it's gone.
 
Hmm, today it's started again.

Build r13088

I enabled verbose log, but it doesn't show anything (if I am looking in right places - it is add more info to sessions txts, isn't it?)

I am experiencing twiddle right after breakfast.

Log:
Code:
You acquire an effect: Oily Flavor (duration: 30 Adventures)

use 1 mojo filter

use 1 oily paste
You gain 7 Adventures
You gain 19 Strengthliness
You gain 27 Magicalness
You gain 23 Roguishness
You acquire an effect: Oily Flavor (duration: 10 Adventures)

add to closet: 99999999 Meat

[35566] The Spirit World
Encounter: spirit bedbug
Round 0: Smiling Spectre wins initiative!

CLI:
Code:
You acquire an effect: Oily Flavor (duration: 30 Adventures)
Finished using 3 oily paste.
Using 1 mojo filter...
Finished using 1 mojo filter.
Using 1 oily paste...
You gain 7 Adventures
You gain 19 Strengthliness
You gain 27 Magicalness
You gain 23 Roguishness
You acquire an effect: Oily Flavor (duration: 10 Adventures)
Finished using 1 oily paste.

[35566] The Spirit World
Encounter: spirit bedbug
Round 0: Smiling Spectre wins initiative!
KoLmafia thinks it is round 2 but KoL thinks it is round 1

Relay browser:
Code:
You twiddle your thumbs.
 

Darzil

Developer
Debug log is found under Help -> Start Debug log. Does get huge though if you don't know when the issue is going to occur.
 

Veracity

Developer
Staff member
I said DEBUG log, not session log.

For something less huge, try this:

Log in
In the gcli: debug trace on
Do your stuff in the Relay Browser
When you see a twiddle message, in the gcli: debug trace off
post the TRACE_<date>.txt file from your KoLmafia directory
 
Sorry, I didn't knew about debug log. I will try this next time, thank you.

---addition---
Wow, it's big. Ok, here is the end of log - I am in exactly needed fight, but steal already before noticed twiddle message.

Code:
1384014854330: Requesting: http://www.kingdomofloathing.com/charpane.php
1384014854330: From Browser: GET /onfocus.1.js HTTP/1.1
1384014854331: From Browser: GET /combatfilter.1.js HTTP/1.1
1384014854366: To Browser: HTTP/1.1 200 OK: /basics.js
1384014854367: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014854369: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384014854369: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384014854613: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384014854645: To Browser: HTTP/1.1 200 OK: /charpane.php
1384014854659: From Browser: GET /basics.js HTTP/1.1
1384014854665: To Browser: HTTP/1.1 200 OK: /basics.js
1384014855739: From Browser: GET /newchatmessages.php?lasttime=0000004727&afk=0 HTTP/1.1
1384014855740: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438498&pwd
1384014856023: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438498&pwd
1384014856027: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004727&afk=0
1384014860551: From Browser: GET /newchatmessages.php?lasttime=0000004728&afk=0 HTTP/1.1
1384014860552: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438509&pwd
1384014860833: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438509&pwd
1384014860837: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004728&afk=0
1384014861714: From Browser: POST /fight.php HTTP/1.1
1384014861714: Requesting: http://www.kingdomofloathing.com/fight.php?action=steal
1384014862056: Retrieved: http://www.kingdomofloathing.com/fight.php?action=steal
1384014862704: To Browser: HTTP/1.1 200 OK: /fight.php
1384014862734: From Browser: GET /charpane.php HTTP/1.1
1384014862734: From Browser: GET /basics.js HTTP/1.1
1384014862734: From Browser: GET /basics.1.css HTTP/1.1
1384014862734: Requesting: http://www.kingdomofloathing.com/charpane.php
1384014862734: From Browser: GET /onfocus.1.js HTTP/1.1
1384014862735: From Browser: GET /combatfilter.1.js HTTP/1.1
1384014862741: To Browser: HTTP/1.1 200 OK: /basics.js
1384014862742: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014862742: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384014862742: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384014863021: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384014863047: To Browser: HTTP/1.1 200 OK: /charpane.php
1384014863063: From Browser: GET /basics.js HTTP/1.1
1384014863068: To Browser: HTTP/1.1 200 OK: /basics.js
1384014863070: From Browser: GET /basics.1.css HTTP/1.1
1384014863079: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014865363: From Browser: GET /newchatmessages.php?lasttime=0000004729&afk=0 HTTP/1.1
1384014865364: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438514&pwd
1384014865645: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438514&pwd
1384014865649: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004729&afk=0
1384014867891: From Browser: POST /fight.php HTTP/1.1
1384014867892: Requesting: http://www.kingdomofloathing.com/fight.php?action=skill&whichskill=7117
1384014868223: Retrieved: http://www.kingdomofloathing.com/fight.php?action=skill&whichskill=7117
1384014868890: To Browser: HTTP/1.1 200 OK: /fight.php
1384014868914: From Browser: GET /charpane.php HTTP/1.1
1384014868915: Requesting: http://www.kingdomofloathing.com/charpane.php
1384014868955: From Browser: GET /basics.js HTTP/1.1
1384014868955: From Browser: GET /basics.1.css HTTP/1.1
1384014868955: From Browser: GET /images/itemimages/shot.gif HTTP/1.1
1384014868955: From Browser: GET /onfocus.1.js HTTP/1.1
1384014868955: From Browser: GET /combatfilter.1.js HTTP/1.1
1384014868963: To Browser: HTTP/1.1 200 OK: /basics.js
1384014868964: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384014868964: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384014868964: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014868971: To Browser: HTTP/1.1 200 OK: /images/itemimages/shot.gif
1384014869200: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384014869226: To Browser: HTTP/1.1 200 OK: /charpane.php
1384014869241: From Browser: GET /basics.js HTTP/1.1
1384014869247: To Browser: HTTP/1.1 200 OK: /basics.js
1384014869250: From Browser: GET /basics.1.css HTTP/1.1
1384014869257: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014870181: From Browser: GET /newchatmessages.php?lasttime=0000004730&afk=0 HTTP/1.1
1384014870181: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438522&pwd
1384014870460: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438522&pwd
1384014870464: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004730&afk=0
1384014874994: From Browser: GET /newchatmessages.php?lasttime=0000004731&afk=0 HTTP/1.1
1384014874994: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438529&pwd
1384014875274: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438529&pwd
1384014875277: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004731&afk=0
1384014879804: From Browser: GET /newchatmessages.php?lasttime=0000004732&afk=0 HTTP/1.1
1384014879804: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438540&pwd
1384014880088: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438540&pwd
1384014880092: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004732&afk=0
1384014884612: From Browser: GET /newchatmessages.php?lasttime=0000004733&afk=0 HTTP/1.1
1384014884612: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438540&pwd
1384014884896: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438540&pwd
1384014884898: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004733&afk=0
1384014889419: From Browser: GET /newchatmessages.php?lasttime=0000004734&afk=0 HTTP/1.1
1384014889419: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438547&pwd
1384014889698: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438547&pwd
1384014889701: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004734&afk=0
1384014894227: From Browser: GET /newchatmessages.php?lasttime=0000004735&afk=0 HTTP/1.1
1384014894228: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438547&pwd
1384014894509: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438547&pwd
1384014894513: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004735&afk=0
1384014899039: From Browser: GET /newchatmessages.php?lasttime=0000004736&afk=0 HTTP/1.1
1384014899040: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438555&pwd
1384014899323: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438555&pwd
1384014899332: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004736&afk=0
1384014903856: From Browser: GET /newchatmessages.php?lasttime=0000004737&afk=0 HTTP/1.1
1384014903856: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438558&pwd
1384014904137: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438558&pwd
1384014908959: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004738&afk=0
1384014913485: From Browser: GET /newchatmessages.php?lasttime=0000004739&afk=0 HTTP/1.1
1384014913485: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438568&pwd
1384014913768: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438568&pwd
1384014913772: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004739&afk=0
1384014918292: From Browser: GET /newchatmessages.php?lasttime=0000004740&afk=0 HTTP/1.1
1384014918292: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438580&pwd
1384014918574: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438580&pwd
1384014918578: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004740&afk=0
1384014923103: From Browser: GET /newchatmessages.php?lasttime=0000004741&afk=0 HTTP/1.1
1384014923103: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438582&pwd
1384014923386: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438582&pwd
1384014923390: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004741&afk=0
1384014927921: From Browser: GET /newchatmessages.php?lasttime=0000004742&afk=0 HTTP/1.1
1384014927921: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438593&pwd
1384014928201: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438593&pwd
1384014928203: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004742&afk=0
1384014932729: From Browser: GET /newchatmessages.php?lasttime=0000004743&afk=0 HTTP/1.1
1384014932730: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438594&pwd
1384014933014: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438594&pwd
1384014933025: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004743&afk=0
1384014937554: From Browser: GET /newchatmessages.php?lasttime=0000004744&afk=0 HTTP/1.1
1384014937554: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438598&pwd
1384014937841: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438598&pwd
1384014937845: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004744&afk=0
1384014942357: From Browser: GET /newchatmessages.php?lasttime=0000004745&afk=0 HTTP/1.1
1384014942357: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438598&pwd
1384014942571: From Browser: POST /fight.php HTTP/1.1
1384014942571: Requesting: http://www.kingdomofloathing.com/fight.php?action=attack
1384014942643: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438598&pwd
1384014942645: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004745&afk=0
1384014943573: Retrieved: http://www.kingdomofloathing.com/fight.php?action=attack
1384014943654: To Browser: HTTP/1.1 200 OK: /fight.php
1384014943685: From Browser: GET /basics.js HTTP/1.1
1384014943685: From Browser: GET /basics.1.css HTTP/1.1
1384014943685: From Browser: GET /onfocus.1.js HTTP/1.1
1384014943685: From Browser: GET /combatfilter.1.js HTTP/1.1
1384014943704: To Browser: HTTP/1.1 200 OK: /basics.js
1384014943704: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384014943704: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014943707: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384014943723: From Browser: GET /charpane.php HTTP/1.1
1384014943725: Requesting: http://www.kingdomofloathing.com/charpane.php
1384014944122: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384014944151: To Browser: HTTP/1.1 200 OK: /charpane.php
1384014944163: From Browser: GET /basics.js HTTP/1.1
1384014944164: From Browser: GET /basics.1.css HTTP/1.1
1384014944166: To Browser: HTTP/1.1 200 OK: /basics.js
1384014944171: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384014947162: From Browser: GET /newchatmessages.php?lasttime=0000004746&afk=0 HTTP/1.1
1384014947163: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438603&pwd
1384014947442: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438603&pwd
1384014947446: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004746&afk=0
1384014951978: From Browser: GET /newchatmessages.php?lasttime=0000004747&afk=0 HTTP/1.1
1384014951978: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438606&pwd
1384014952256: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438606&pwd
1384014952259: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004747&afk=0
1384014956794: From Browser: GET /newchatmessages.php?lasttime=0000004748&afk=0 HTTP/1.1
1384014956795: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438610&pwd
1384014957075: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438610&pwd
1384014957078: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004748&afk=0
1384014961605: From Browser: GET /newchatmessages.php?lasttime=0000004749&afk=0 HTTP/1.1
1384014961606: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014961885: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014961888: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004749&afk=0
1384014966412: From Browser: GET /newchatmessages.php?lasttime=0000004750&afk=0 HTTP/1.1
1384014966412: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014966689: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014966700: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004750&afk=0
1384014971222: From Browser: GET /newchatmessages.php?lasttime=0000004751&afk=0 HTTP/1.1
1384014971223: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014971508: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438611&pwd
1384014976313: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004752&afk=0
1384014980843: From Browser: GET /newchatmessages.php?lasttime=0000004753&afk=0 HTTP/1.1
1384014980844: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014981126: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014981129: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004753&afk=0
1384014985651: From Browser: GET /newchatmessages.php?lasttime=0000004754&afk=0 HTTP/1.1
1384014985651: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014985928: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014985931: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004754&afk=0
1384014990462: From Browser: GET /newchatmessages.php?lasttime=0000004755&afk=0 HTTP/1.1
1384014990462: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014990743: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438620&pwd
1384014990745: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004755&afk=0
1384014995271: From Browser: GET /newchatmessages.php?lasttime=0000004756&afk=0 HTTP/1.1
1384014995271: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438631&pwd
1384014995572: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438631&pwd
1384014995575: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004756&afk=0
1384015000094: From Browser: GET /newchatmessages.php?lasttime=0000004757&afk=0 HTTP/1.1
1384015000095: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438633&pwd
1384015000251: From Browser: GET /adventure.php?snarfblat=365 HTTP/1.1
1384015000252: Requesting: http://www.kingdomofloathing.com/adventure.php?snarfblat=365
1384015000371: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438633&pwd
1384015000382: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004757&afk=0
1384015000811: Retrieved: http://www.kingdomofloathing.com/adventure.php?snarfblat=365
1384015000812: To Browser: HTTP/1.1 302 Found: /adventure.php?snarfblat=365
1384015000820: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015000821: Requesting: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015001158: Retrieved: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015001736: To Browser: HTTP/1.1 200 OK: /fight.php?ireallymeanit=1384015003
1384015001759: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015001760: Requesting: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015001762: From Browser: GET /images/scripts/keybinds.min.2.js HTTP/1.1
1384015001762: From Browser: GET /charpane.php HTTP/1.1
1384015001763: Requesting: http://www.kingdomofloathing.com/charpane.php
1384015001766: To Browser: HTTP/1.1 200 OK: /images/scripts/keybinds.min.2.js
1384015002063: Retrieved: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015002089: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384015002108: To Browser: HTTP/1.1 200 OK: /charpane.php
1384015002118: From Browser: GET /basics.js HTTP/1.1
1384015002121: From Browser: GET /basics.1.css HTTP/1.1
1384015002126: To Browser: HTTP/1.1 200 OK: /basics.js
1384015002126: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384015002367: To Browser: HTTP/1.1 200 OK: /fight.php?ireallymeanit=1384015003
1384015002380: From Browser: GET /onfocus.1.js HTTP/1.1
1384015002380: From Browser: GET /combatfilter.1.js HTTP/1.1
1384015002385: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384015002385: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384015004362: From Browser: POST /fight.php HTTP/1.1
1384015004363: Requesting: http://www.kingdomofloathing.com/fight.php?action=steal
1384015004685: Retrieved: http://www.kingdomofloathing.com/fight.php?action=steal
1384015004915: From Browser: GET /newchatmessages.php?lasttime=0000004758&afk=0 HTTP/1.1
1384015004915: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438637&pwd
1384015005194: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438637&pwd
1384015005197: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004758&afk=0
1384015005305: To Browser: HTTP/1.1 200 OK: /fight.php
1384015005320: From Browser: GET /charpane.php HTTP/1.1
1384015005321: Requesting: http://www.kingdomofloathing.com/charpane.php
1384015005328: From Browser: GET /basics.js HTTP/1.1
1384015005328: From Browser: GET /basics.1.css HTTP/1.1
1384015005328: From Browser: GET /onfocus.1.js HTTP/1.1
1384015005328: From Browser: GET /combatfilter.1.js HTTP/1.1
1384015005333: To Browser: HTTP/1.1 200 OK: /basics.js
1384015005334: To Browser: HTTP/1.1 200 OK: /combatfilter.1.js
1384015005334: To Browser: HTTP/1.1 200 OK: /basics.1.css
1384015005334: To Browser: HTTP/1.1 200 OK: /onfocus.1.js
1384015005604: Retrieved: http://www.kingdomofloathing.com/charpane.php
1384015005636: To Browser: HTTP/1.1 200 OK: /charpane.php
1384015005685: From Browser: GET /basics.js HTTP/1.1
1384015005687: To Browser: HTTP/1.1 200 OK: /basics.js
1384015009726: From Browser: GET /newchatmessages.php?lasttime=0000004759&afk=0 HTTP/1.1
1384015009727: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438640&pwd
1384015009999: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438640&pwd
1384015010002: To Browser: HTTP/1.1 200 OK: /newchatmessages.php?lasttime=0000004759&afk=0
1384015014527: From Browser: GET /newchatmessages.php?lasttime=0000004760&afk=0 HTTP/1.1
1384015014527: Requesting: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438640&pwd
1384015014805: Retrieved: http://www.kingdomofloathing.com/newchatmessages.php?lasttime=1360438640&pwd
There is that in CLI at this time:
Code:
Configuring available monsters.
Fax list fetched.

[36337] The Spirit World
Encounter: spirit faucet
Round 0: Smiling Spectre wins initiative!
Round 1: Smiling Spectre tries to steal an item!
You acquire an item: spirit gum
Round 2: Smiling Spectre casts SIPHON SPIRIT!
You acquire an item: Shot of the Living Dead
Round 3: Smiling Spectre attacks!
Round 4: spirit faucet takes 704 damage.
Round 4: Smiling Spectre wins the fight!
You gain 20 hit points
You gain 7 Mojo Points
After Battle: Medium Happy looks at you intently. "You have a lovely sepia aura. That's unusual for an adventurer," she says, smiling.
You gain 33 Fortitude
You gain a Muscle point!
You gain 33 Magicalness
You gain 76 Sarcasm
Libram summon #20 when MP > 390.

[36338] The Spirit World
Encounter: spirit faucet
Round 0: Smiling Spectre wins initiative!
KoLmafia thinks it is round 2 but KoL thinks it is round 1
Round 2: Smiling Spectre tries to steal an item!
KoLmafia thinks it is round 3 but KoL thinks it is round 2
You acquire an item: spirit gum
You gain 58 Meat.
 
Last edited:

Veracity

Developer
Staff member
I finally got around to looking at your TRACE log. It was illuminating. Here is the relevant portion, with chat, charpane, images, etc. removed:

Code:
1384015000251: From Browser: GET /adventure.php?snarfblat=365 HTTP/1.1
1384015000252: Requesting: http://www.kingdomofloathing.com/adventure.php?snarfblat=365
1384015000811: Retrieved: http://www.kingdomofloathing.com/adventure.php?snarfblat=365
1384015000812: To Browser: HTTP/1.1 302 Found: /adventure.php?snarfblat=365
1384015000820: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015000821: Requesting: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015001158: Retrieved: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015001736: To Browser: HTTP/1.1 200 OK: /fight.php?ireallymeanit=1384015003
1384015001759: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015001760: Requesting: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015002063: Retrieved: http://www.kingdomofloathing.com/fight.php?ireallymeanit=1384015003
1384015002367: To Browser: HTTP/1.1 200 OK: /fight.php?ireallymeanit=1384015003
1384015004362: From Browser: POST /fight.php HTTP/1.1
1384015004363: Requesting: http://www.kingdomofloathing.com/fight.php?action=steal
1384015004685: Retrieved: http://www.kingdomofloathing.com/fight.php?action=steal
1384015005305: To Browser: HTTP/1.1 200 OK: /fight.php
Or to be even more terse, here are the requests that your Browser submitted to KoLmafia:

Code:
1384015000251: From Browser: GET /adventure.php?snarfblat=365 HTTP/1.1
1384015000820: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015001759: From Browser: GET /fight.php?ireallymeanit=1384015003 HTTP/1.1
1384015004362: From Browser: POST /fight.php HTTP/1.1
Your browser submitted the "ireallymeantit" request to initiate the fight - twice. As a good HTTP proxy, KoLmafia dutifully did what your browser told it to do and passed both of them along to KoL. KoL responded to the second one with a "You twiddle your thumbs".

I don't know why your Browser submitted the request twice. Perhaps you had a Greasemonkey script "helping" you. But this is not a bug in KoLmafia.
 
Top