Xian55 / WowClassicGrindBot

Highly configurable and responsive World of Warcraft Classic pixel Grind Bot - No DLL injection or memory tampering, just screen capture and input simulation.
161 stars 117 forks source link

Randomly not looting #556

Closed adamssullivan closed 4 months ago

adamssullivan commented 6 months ago

Description

While killing the bot will sometimes loot and sometimes not loot. There doesn't seem to be any rime or reason. It just kills the mob and then moves on

Addon Version

1.7.50

World of Warcraft Client

Season of Mastery

Reproduction Steps

The bot will kill and then not loot randomly

Last Good Version

No response

Screenshots

No response

Logs

out20231228.log

Xian55 commented 6 months ago

Gooday!

Thanks for reaching out for help.

To avoid settling in with premature conclusion, I've been trying to ingest the provided log.

If i seeing correctly you are in a somewhat highly contested area, where one or more other players trying to tag murlocks.

Out of the logged 15 possible kills only 9 has been detected as loot. (comparing the Lost Target! with Kill credit detected! counts)

Out of the 9 detected kills, frankly everyone seems to be successful (searching for [LootGoal ] Loot Successful)


Can you check that, you don't have any Lua errors in game ?


I'm aware that the kill detection has its limitations due, have to compress a GUID value down to 24bit, and because of that, there's a case when multiple mobs sharing the same unique value.

The compressor currently considers the creatureID/npcId and spawnUID(which is consist of time from epoch and an auto incremented value)

Basically parsing a value like this "Creature-0-1465-0-2105-448-000043F59F" and returns with a unique number

function DataToColor:uniqueGuid(npcId, spawn)
    local spawnEpochOffset = band(tonumber(sub(spawn, 5), 16), 0x7fffff)
    local spawnIndex = band(tonumber(sub(spawn, 1, 5), 16), 0xffff8)

    local dd = date("*t", spawnEpochOffset)
    return (
        dd.day +
        dd.hour +
        dd.min +
        dd.sec +
        npcId +
        spawnIndex
    ) % 0x1000000
end

That i can take a look to reduce collision generation (two killed mob share the same generated number).


Are you aware of any high server latency / lagg ?

function DataToColor:OnLeftCombat()
    DataToColor.eligibleKillCredit = {}
end

Are you playing the game with a steady FPS count ?


This is non related to the looting issue, for me it seems from the log that casting Wrath sometimes does not work properly ?

Can you tell something about that, have you noticed anything ?

Xian55 commented 6 months ago

I've tried to come up with different ways to generate unique values for npcId spawnUID for the Creature however all of them has at least one collision in a 50 sample set.

I've made a patch which shows my current progress, if you wish to contribute or think about the problem this should give a good base to start thinking about it.

On the other hand if you have an another approach to this problem you are more then welcome to share it here.

The test_gen_unique_from_guid.patch adds a GuidTest.lua file next to the addon, there's a few steps in order to setup up

Then in-game you can type dctest to run your function solution, if anyone interested.

There's a few requirement for the solution

adamssullivan commented 6 months ago

Hello. Thanks for all the info. It's a bit beyond me. The main issue is probably caused by server lag or instability. The mob takes too long to become lootable? Anyways it seems to work well enough and I always appreciate any time or effort you put into this project.

Xian55 commented 6 months ago

The mob takes too long to become lootable?

As most of the bot features, it can only await for so long to compete an action. There's a meaningful wait time for every corresponding action.

After the mob dies, the player has a maximum of 10s time window to loot the mob, after that time it marked as non reachable, and its going to be ignored.

adamssullivan commented 6 months ago

Okay. Well, it must be something else. As it does not take 10s to loot. It's running away almost as soon as it dies. So it must be something to clue it that it has died.

Xian55 commented 6 months ago

If you could send me more logs, i might have a better understanding about your situation.

adamssullivan commented 6 months ago

Will do.

augerT commented 6 months ago

Also having this issue. Playing on ~90 ping and seems to fail pretty frequently. Playing a hunter. Perhaps it has something to do with pet getting kills ?

Here's a failed loot log:

[13:31:51:996 I] [NpcNameFinder] type = None | mode = Fuzzy [13:31:51:996 I] [GoapAgent ] New Plan= Approach Target [13:31:52:073 I] [FollowRouteGoal] Found target! [13:31:53:768 I] [GoapAgent ] New Plan= Pull Target [13:31:53:832 I] [PullTargetGoal ] Stop auto interact! [13:31:54:458 I] [Hunter's Mark' ] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [13:31:54:773 I] [Hunter's Mark' ] instant input 60ms SPELL_FAILED_NOT_READY -253.8547ms [13:31:55:872 I] [BagChangeTracker] 13 -> 12 Sharp Arrow [13:31:55:872 I] [Serpent Sting ] instant input 78ms CAST_SUCCESS 177.4064ms [13:31:55:873 I] [Serpent Sting ] ... AfterCastWaitCombat 0ms [13:31:55:885 I] [GoapAgent ] New Plan= Combat [13:31:56:465 I] [BagChangeTracker] 12 -> 11 Sharp Arrow [13:31:57:349 I] [Multi-Shot ] instant input 77ms CAST_START 82.2117ms [13:31:57:545 I] [GoapAgent ] New Plan= Approach Target [13:31:57:859 I] [GoapAgent ] New Plan= Combat [13:31:58:416 I] [Stepback ] instant input 474ms [13:31:58:428 I] [GoapAgent ] New Plan= Approach Target [13:31:58:646 I] [GoapAgent ] New Plan= Combat [13:31:58:943 I] [Raptor Strike ] instant input 60ms CAST_SENT 157.4197ms [13:32:02:054 I] [Stepback ] instant input 3013ms [13:32:02:482 I] [Auto Shot ] instant input 77ms CAST_START 113.9491ms [13:32:03:140 I] [BagChangeTracker] 11 -> 10 Sharp Arrow [13:32:03:582 I] [CombatGoal ] Lost target! [13:32:03:878 W] [GoapAgent ] New Plan= NO PLAN [13:32:04:786 I] [GoapAgent ] New Plan= Follow Route

Xian55 commented 6 months ago

Seems like the same issue as before, after the Lost target! message happens, there supposed to be a Kill credit detected!.

Do you have other players around, kills mobs on respawn, with high intensity ?

augerT commented 6 months ago

It's pretty low intensity I'd say. Few people here and there but my route is pretty low density.

Edit: just saw it fail with like one other mob on my screen and not a player in sight around me. Same log sequence

Xian55 commented 6 months ago

After doing some testing on my end, i can only reliably reproduce the mentioned issue of Kill credit detected! does not showing up in the logs when the player is gaining a level. I did not pay attention the whole time, since it takes a somewhat long time to reproduce, however since now i know a repro step, i can dig in deeper, find out what's going on.

Xian55 commented 6 months ago

Meanwhile i did some further testing, however, after all, the level up during a kill method does not seems to be reliable at all. I guess it was just pure luck at the first place.

I've been trying to wrap my head around what kind of edge case might happen, but i only have a few theories. Not so long ago i completely moved the kill credit logic to the Addon(DataToColor). It is responsible to determine what kill counts as kill credit. It meant to reduce the risk of skipping potential kill credits 😓 #23 #139 #162 #400 #401

When you as a player either do damage to or take damage from a given target, it checks if the target is UnitIsTapDenied. If not, then it adds to the DataToColor.eligibleKillCredit table.

Meanwhile listening to global events like UNIT_DIED which includes everything in the players proximity. When the given unit dies, it looks up if it exists in the DataToColor.eligibleKillCredit table, if yes, then it pushes the "Unique ID" to the DataToColor.CombatCreatureDiedQueue. Which will eventually flushed to the screen and remains for COMBAT_LOG_ITERATION_FRAME_CHANGE_RATE update ticks which is 5. It should be plenty of time for the backend to recognize the change. This time could be increased, but then many other features which uses this timer wont work(inventory, spells, actionbars)

I was unable to spot an edge case in that logic, personally.


DataToColor.eligibleKillCredit is cleared once the player leaves combat, so there might be a case when the player kills a LOT of mobs suddenly with like AoE, but right now i would not worry about that case.


[13:31:57:349 I] [Multi-Shot ] instant input 77ms CAST_START 82.2117ms

It might have to do something about AoE abilities such as Multi Shot, because you can damage multiple targets at the same time, while you can have only one target. So in theory you could fight with 2 targets, and lets assume the first dies because of its being your primary target, and the other one ends up so low hp, that you wont have time for an Auto Shot or a single target special ability, in that case targetGuid == destGUID, where the destGUID is who took the damage from you, not matches.

However in the past i've seen such instance when i was just using single target damaging abilities and the Kill credit detected! message wasn't showed up either.


Playing a hunter. Perhaps it has something to do with pet getting kills ?

In case only your pet is doing the damage on the NPC, and you as a player have not done any auto attack/ability on the target to cause damage, you won't get credit for the kill.

thejefu commented 5 months ago

First day of trying this out on a priest and I've had the same problem from the get go; character is immediately going after a new target some 60% of the time. Everything else has been near flawless.

I'll get a clean log shortly and share.

thejefu commented 5 months ago

Following is a run of killing 4 monsters. The first 3 were ordinary kills that followed the sequence. None of these paused to loot or attempted to loot. The 4th mob was fudged and didn't follow the sequence for one reason or another (still perfecting it) but it did stop to loot.

Only observation I can make is that the first 3 monsters were all in relative close proximity whilst the 4th was isolated.

[08:09:27:677 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:09:27:679 I] [Interact ] Non Actionbar I -> I [08:09:27:680 I] [InteractMouseOver] Non Actionbar J -> J [08:09:27:680 I] [Approach ] Non Actionbar I -> I [08:09:27:682 I] [AutoAttack ] Non Actionbar I -> I [08:09:27:682 I] [TargetLastTarget] Non Actionbar G -> G [08:09:27:683 I] [StandUp ] Non Actionbar X -> X [08:09:27:683 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:09:27:684 I] [StopAttack ] Non Actionbar Delete -> Delete [08:09:27:685 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:09:27:686 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:09:27:686 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:09:27:687 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:09:27:687 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:09:27:688 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:09:27:689 I] [Mount ] Non Actionbar O -> O [08:09:27:690 I] [Pull] Init Binds(Cost, Cooldown) [08:09:27:691 I] [Combat] Init Binds(Cost, Cooldown) [08:09:27:692 I] [Adhoc] Init Binds(Cost, Cooldown) [08:09:27:692 I] [Parallel] Init Binds(Cost, Cooldown) [08:09:27:695 I] [WaitKeyActions] Added Drink Buff to await Drink [08:09:27:696 I] [Pull] Init KeyActions [08:09:27:699 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:09:27:706 I] [Penance ] Requirement: "Mana%>50" [08:09:27:707 I] [Combat] Init KeyActions [08:09:27:707 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:09:27:708 I] [Renew ] Requirement: "Health%<40" [08:09:27:708 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:09:27:709 I] [Lesser Heal ] Requirement: "Health%<40" [08:09:27:709 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:09:27:710 I] [Power Word: Shield] Requirement: "!Shield" [08:09:27:711 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:09:27:711 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:09:27:712 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:09:27:712 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:09:27:713 I] [Mind Blast ] Requirement: "MobCount>1" [08:09:27:716 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:09:27:718 I] [Adhoc] Init KeyActions [08:09:27:718 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:09:27:719 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:09:27:719 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:09:27:720 I] [Inner Fire ] Requirement: "not Inner Fire" [08:09:27:720 I] [Parallel] Init KeyActions [08:09:27:722 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:09:27:723 I] [Drink ] Requirement: "Mana%<30" [08:09:27:723 I] [Drink ] Requirement: "!Drink" [08:09:27:724 I] [Drink ] Requirement: "!Swimming" [08:09:27:724 I] [Drink ] Requirement: "!Falling" [08:09:27:724 I] [Wait] Init KeyActions [08:09:27:725 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:09:27:726 I] [Drink Buff ] Requirement: "Drink" [08:09:27:726 I] [Drink Buff ] Requirement: "Mana% < 100" [08:09:27:728 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:09:27:743 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:09:27:770 I] [BotController ] Elapsed time: 126.2104 ms [08:09:29:329 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:09:29:330 I] [Interact ] Non Actionbar I -> I [08:09:29:331 I] [InteractMouseOver] Non Actionbar J -> J [08:09:29:331 I] [Approach ] Non Actionbar I -> I [08:09:29:332 I] [AutoAttack ] Non Actionbar I -> I [08:09:29:332 I] [TargetLastTarget] Non Actionbar G -> G [08:09:29:333 I] [StandUp ] Non Actionbar X -> X [08:09:29:333 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:09:29:334 I] [StopAttack ] Non Actionbar Delete -> Delete [08:09:29:335 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:09:29:335 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:09:29:336 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:09:29:336 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:09:29:337 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:09:29:338 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:09:29:338 I] [Mount ] Non Actionbar O -> O [08:09:29:339 I] [Pull] Init Binds(Cost, Cooldown) [08:09:29:339 I] [Combat] Init Binds(Cost, Cooldown) [08:09:29:340 I] [Adhoc] Init Binds(Cost, Cooldown) [08:09:29:340 I] [Parallel] Init Binds(Cost, Cooldown) [08:09:29:341 I] [WaitKeyActions] Added Drink Buff to await Drink [08:09:29:342 I] [Pull] Init KeyActions [08:09:29:342 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:09:29:342 I] [Penance ] Requirement: "Mana%>50" [08:09:29:343 I] [Combat] Init KeyActions [08:09:29:343 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:09:29:344 I] [Renew ] Requirement: "Health%<40" [08:09:29:345 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:09:29:346 I] [Lesser Heal ] Requirement: "Health%<40" [08:09:29:347 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:09:29:348 I] [Power Word: Shield] Requirement: "!Shield" [08:09:29:349 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:09:29:351 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:09:29:354 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:09:29:360 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:09:29:360 I] [Mind Blast ] Requirement: "MobCount>1" [08:09:29:361 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:09:29:362 I] [Adhoc] Init KeyActions [08:09:29:362 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:09:29:363 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:09:29:363 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:09:29:364 I] [Inner Fire ] Requirement: "not Inner Fire" [08:09:29:365 I] [Parallel] Init KeyActions [08:09:29:365 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:09:29:366 I] [Drink ] Requirement: "Mana%<30" [08:09:29:366 I] [Drink ] Requirement: "!Drink" [08:09:29:367 I] [Drink ] Requirement: "!Swimming" [08:09:29:367 I] [Drink ] Requirement: "!Falling" [08:09:29:368 I] [Wait] Init KeyActions [08:09:29:368 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:09:29:372 I] [Drink Buff ] Requirement: "Drink" [08:09:29:372 I] [Drink Buff ] Requirement: "Mana% < 100" [08:09:29:373 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:09:29:375 D] [GoapAgent ] Thread stopped! [08:09:29:375 D] [GrindSessionHandler] Thread stopped! [08:09:29:375 D] [Navigation ] Thread stopped! [08:09:29:375 D] [Navigation ] Thread stopped! [08:09:29:375 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:09:29:376 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:09:29:387 I] [BotController ] Elapsed time: 59.9939 ms [08:09:33:121 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:09:33:122 I] [Interact ] Non Actionbar I -> I [08:09:33:122 I] [InteractMouseOver] Non Actionbar J -> J [08:09:33:123 I] [Approach ] Non Actionbar I -> I [08:09:33:124 I] [AutoAttack ] Non Actionbar I -> I [08:09:33:124 I] [TargetLastTarget] Non Actionbar G -> G [08:09:33:125 I] [StandUp ] Non Actionbar X -> X [08:09:33:125 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:09:33:126 I] [StopAttack ] Non Actionbar Delete -> Delete [08:09:33:127 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:09:33:127 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:09:33:128 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:09:33:128 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:09:33:129 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:09:33:130 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:09:33:130 I] [Mount ] Non Actionbar O -> O [08:09:33:132 I] [Pull] Init Binds(Cost, Cooldown) [08:09:33:135 I] [Combat] Init Binds(Cost, Cooldown) [08:09:33:135 I] [Adhoc] Init Binds(Cost, Cooldown) [08:09:33:136 I] [Parallel] Init Binds(Cost, Cooldown) [08:09:33:137 I] [WaitKeyActions] Added Drink Buff to await Drink [08:09:33:138 I] [Pull] Init KeyActions [08:09:33:138 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:09:33:138 I] [Penance ] Requirement: "Mana%>50" [08:09:33:139 I] [Combat] Init KeyActions [08:09:33:139 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:09:33:140 I] [Renew ] Requirement: "Health%<40" [08:09:33:140 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:09:33:142 I] [Lesser Heal ] Requirement: "Health%<40" [08:09:33:142 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:09:33:143 I] [Power Word: Shield] Requirement: "!Shield" [08:09:33:143 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:09:33:144 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:09:33:145 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:09:33:145 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:09:33:146 I] [Mind Blast ] Requirement: "MobCount>1" [08:09:33:147 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:09:33:151 I] [Adhoc] Init KeyActions [08:09:33:152 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:09:33:152 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:09:33:153 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:09:33:153 I] [Inner Fire ] Requirement: "not Inner Fire" [08:09:33:154 I] [Parallel] Init KeyActions [08:09:33:155 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:09:33:155 I] [Drink ] Requirement: "Mana%<30" [08:09:33:156 I] [Drink ] Requirement: "!Drink" [08:09:33:157 I] [Drink ] Requirement: "!Swimming" [08:09:33:157 I] [Drink ] Requirement: "!Falling" [08:09:33:158 I] [Wait] Init KeyActions [08:09:33:158 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:09:33:158 I] [Drink Buff ] Requirement: "Drink" [08:09:33:159 I] [Drink Buff ] Requirement: "Mana% < 100" [08:09:33:159 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:09:33:161 D] [GoapAgent ] Thread stopped! [08:09:33:161 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:09:33:161 D] [GrindSessionHandler] Thread stopped! [08:09:33:161 D] [Navigation ] Thread stopped! [08:09:33:161 D] [Navigation ] Thread stopped! [08:09:33:161 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:09:33:180 I] [BotController ] Elapsed time: 60.218 ms [08:09:49:090 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:09:49:091 I] [Interact ] Non Actionbar I -> I [08:09:49:092 I] [InteractMouseOver] Non Actionbar J -> J [08:09:49:092 I] [Approach ] Non Actionbar I -> I [08:09:49:092 I] [AutoAttack ] Non Actionbar I -> I [08:09:49:093 I] [TargetLastTarget] Non Actionbar G -> G [08:09:49:094 I] [StandUp ] Non Actionbar X -> X [08:09:49:094 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:09:49:095 I] [StopAttack ] Non Actionbar Delete -> Delete [08:09:49:095 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:09:49:096 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:09:49:096 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:09:49:097 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:09:49:097 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:09:49:099 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:09:49:099 I] [Mount ] Non Actionbar O -> O [08:09:49:100 I] [Pull] Init Binds(Cost, Cooldown) [08:09:49:100 I] [Combat] Init Binds(Cost, Cooldown) [08:09:49:100 I] [Adhoc] Init Binds(Cost, Cooldown) [08:09:49:101 I] [Parallel] Init Binds(Cost, Cooldown) [08:09:49:105 I] [WaitKeyActions] Added Drink Buff to await Drink [08:09:49:106 I] [Pull] Init KeyActions [08:09:49:106 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:09:49:107 I] [Penance ] Requirement: "Mana%>50" [08:09:49:107 I] [Combat] Init KeyActions [08:09:49:108 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:09:49:108 I] [Renew ] Requirement: "Health%<40" [08:09:49:109 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:09:49:110 I] [Lesser Heal ] Requirement: "Health%<40" [08:09:49:110 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:09:49:111 I] [Power Word: Shield] Requirement: "!Shield" [08:09:49:111 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:09:49:113 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:09:49:113 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:09:49:114 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:09:49:116 I] [Mind Blast ] Requirement: "MobCount>1" [08:09:49:118 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:09:49:122 I] [Adhoc] Init KeyActions [08:09:49:123 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:09:49:124 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:09:49:124 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:09:49:125 I] [Inner Fire ] Requirement: "not Inner Fire" [08:09:49:125 I] [Parallel] Init KeyActions [08:09:49:125 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:09:49:126 I] [Drink ] Requirement: "Mana%<30" [08:09:49:127 I] [Drink ] Requirement: "!Drink" [08:09:49:127 I] [Drink ] Requirement: "!Swimming" [08:09:49:128 I] [Drink ] Requirement: "!Falling" [08:09:49:128 I] [Wait] Init KeyActions [08:09:49:129 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:09:49:129 I] [Drink Buff ] Requirement: "Drink" [08:09:49:130 I] [Drink Buff ] Requirement: "Mana% < 100" [08:09:49:131 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:09:49:132 D] [GoapAgent ] Thread stopped! [08:09:49:132 D] [GrindSessionHandler] Thread stopped! [08:09:49:132 D] [Navigation ] Thread stopped! [08:09:49:132 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:09:49:132 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:09:49:132 D] [Navigation ] Thread stopped! [08:09:49:140 I] [BotController ] Elapsed time: 51.4289 ms [08:10:00:052 I] [GoapAgent ] New Plan= Adhoc [08:10:00:381 I] [Power Word: Fortitude] instant usable: True->False | current: False->False | NONE->CAST_SUCCESS [08:10:00:575 I] [Power Word: Fortitude] instant input 75ms SPELL_FAILED_NOT_READY -112.2478ms [08:10:00:586 I] [GoapAgent ] New Plan= Adhoc [08:10:01:726 I] [Inner Fire ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:10:01:915 I] [Inner Fire ] instant usable: False->False | current: True->False | SPELL_FAILED_NOT_READY->CAST_SUCCESS [08:10:01:931 I] [GoapAgent ] New Plan= Follow Route [08:10:01:935 I] [FollowRouteGoal] RefillWaypoints - findClosest:True - ThereAndBack:False [08:10:02:537 W] [Navigation ] Unable to find path <1994.9556, -458.07886, 0> -> <2224.128, -298.66797, 0>. Character may stuck! 533.3055ms [08:10:05:558 I] [StuckDetector ] Unstuck by turning for 209ms [08:10:05:782 I] [StuckDetector ] Unstuck by moving for 1316ms [08:10:10:419 I] [StuckDetector ] Unstuck by turning for 336ms [08:10:10:785 I] [StuckDetector ] Unstuck by moving for 1311ms [08:10:14:646 I] [NpcNameFinder] type = None | mode = Fuzzy [08:10:14:662 I] [NpcNameFinder] type = None | mode = Fuzzy [08:10:16:551 I] [ExecGameCommand] /testflush [08:10:19:902 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:10:19:903 I] [Interact ] Non Actionbar I -> I [08:10:19:903 I] [InteractMouseOver] Non Actionbar J -> J [08:10:19:904 I] [Approach ] Non Actionbar I -> I [08:10:19:904 I] [AutoAttack ] Non Actionbar I -> I [08:10:19:905 I] [TargetLastTarget] Non Actionbar G -> G [08:10:19:905 I] [StandUp ] Non Actionbar X -> X [08:10:19:906 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:10:19:907 I] [StopAttack ] Non Actionbar Delete -> Delete [08:10:19:907 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:10:19:908 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:10:19:909 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:10:19:911 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:10:19:912 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:10:19:913 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:10:19:913 I] [Mount ] Non Actionbar O -> O [08:10:19:914 I] [Pull] Init Binds(Cost, Cooldown) [08:10:19:915 I] [Combat] Init Binds(Cost, Cooldown) [08:10:19:916 I] [Adhoc] Init Binds(Cost, Cooldown) [08:10:19:917 I] [Parallel] Init Binds(Cost, Cooldown) [08:10:19:917 I] [WaitKeyActions] Added Drink Buff to await Drink [08:10:19:918 I] [Pull] Init KeyActions [08:10:19:919 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:10:19:919 I] [Penance ] Requirement: "Mana%>50" [08:10:19:920 I] [Combat] Init KeyActions [08:10:19:920 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:10:19:921 I] [Renew ] Requirement: "Health%<40" [08:10:19:921 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:10:19:922 I] [Lesser Heal ] Requirement: "Health%<40" [08:10:19:923 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:10:19:923 I] [Power Word: Shield] Requirement: "!Shield" [08:10:19:924 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:10:19:925 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:10:19:930 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:10:19:931 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:10:19:932 I] [Mind Blast ] Requirement: "MobCount>1" [08:10:19:932 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:10:19:933 I] [Adhoc] Init KeyActions [08:10:19:933 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:10:19:934 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:10:19:935 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:10:19:935 I] [Inner Fire ] Requirement: "not Inner Fire" [08:10:19:936 I] [Parallel] Init KeyActions [08:10:19:936 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:10:19:937 I] [Drink ] Requirement: "Mana%<30" [08:10:19:937 I] [Drink ] Requirement: "!Drink" [08:10:19:938 I] [Drink ] Requirement: "!Swimming" [08:10:19:938 I] [Drink ] Requirement: "!Falling" [08:10:19:939 I] [Wait] Init KeyActions [08:10:19:939 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:10:19:940 I] [Drink Buff ] Requirement: "Drink" [08:10:19:941 I] [Drink Buff ] Requirement: "Mana% < 100" [08:10:19:942 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:10:19:947 D] [GoapAgent ] Thread stopped! [08:10:19:947 D] [GrindSessionHandler] Thread stopped! [08:10:19:947 D] [Navigation ] Thread stopped! [08:10:19:947 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:10:19:947 D] [Navigation ] Thread stopped! [08:10:19:947 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:10:19:952 I] [BotController ] Elapsed time: 50.9719 ms [08:10:29:310 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:10:29:310 I] [Interact ] Non Actionbar I -> I [08:10:29:311 I] [InteractMouseOver] Non Actionbar J -> J [08:10:29:311 I] [Approach ] Non Actionbar I -> I [08:10:29:312 I] [AutoAttack ] Non Actionbar I -> I [08:10:29:312 I] [TargetLastTarget] Non Actionbar G -> G [08:10:29:313 I] [StandUp ] Non Actionbar X -> X [08:10:29:314 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:10:29:315 I] [StopAttack ] Non Actionbar Delete -> Delete [08:10:29:315 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:10:29:315 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:10:29:316 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:10:29:316 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:10:29:317 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:10:29:318 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:10:29:318 I] [Mount ] Non Actionbar O -> O [08:10:29:319 I] [Pull] Init Binds(Cost, Cooldown) [08:10:29:320 I] [Combat] Init Binds(Cost, Cooldown) [08:10:29:320 I] [Adhoc] Init Binds(Cost, Cooldown) [08:10:29:321 I] [Parallel] Init Binds(Cost, Cooldown) [08:10:29:321 I] [WaitKeyActions] Added Drink Buff to await Drink [08:10:29:322 I] [Pull] Init KeyActions [08:10:29:323 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:10:29:323 I] [Penance ] Requirement: "Mana%>50" [08:10:29:324 I] [Combat] Init KeyActions [08:10:29:324 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:10:29:325 I] [Renew ] Requirement: "Health%<40" [08:10:29:326 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:10:29:326 I] [Lesser Heal ] Requirement: "Health%<40" [08:10:29:327 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:10:29:329 I] [Power Word: Shield] Requirement: "!Shield" [08:10:29:331 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:10:29:334 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:10:29:340 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:10:29:341 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:10:29:341 I] [Mind Blast ] Requirement: "MobCount>1" [08:10:29:342 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:10:29:342 I] [Adhoc] Init KeyActions [08:10:29:343 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:10:29:343 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:10:29:344 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:10:29:345 I] [Inner Fire ] Requirement: "not Inner Fire" [08:10:29:345 I] [Parallel] Init KeyActions [08:10:29:345 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:10:29:346 I] [Drink ] Requirement: "Mana%<30" [08:10:29:347 I] [Drink ] Requirement: "!Drink" [08:10:29:347 I] [Drink ] Requirement: "!Swimming" [08:10:29:348 I] [Drink ] Requirement: "!Falling" [08:10:29:348 I] [Wait] Init KeyActions [08:10:29:349 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:10:29:349 I] [Drink Buff ] Requirement: "Drink" [08:10:29:350 I] [Drink Buff ] Requirement: "Mana% < 100" [08:10:29:354 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Undead\11-14_Tirisfal Glades_Balnir Farmstead.json [08:10:29:363 D] [GoapAgent ] Thread stopped! [08:10:29:363 D] [GrindSessionHandler] Thread stopped! [08:10:29:363 D] [Navigation ] Thread stopped! [08:10:29:363 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:10:29:363 D] [Navigation ] Thread stopped! [08:10:29:363 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:10:29:370 I] [BotController ] Elapsed time: 61.3052 ms [08:10:41:067 I] [GoapAgent ] New Plan= Follow Route [08:10:41:068 I] [FollowRouteGoal] RefillWaypoints - findClosest:True - ThereAndBack:False [08:10:42:637 I] [FollowRouteGoal] RefillWaypoints - findClosest:False - ThereAndBack:False [08:10:42:637 I] [FollowRouteGoal] RefillWaypoints - Set destination from closest to nearest endpoint - with 6 waypoints [08:10:46:134 I] [FollowRouteGoal] Random jump [08:10:47:085 I] [NpcNameFinder] type = None | mode = Fuzzy [08:10:47:085 I] [GoapAgent ] New Plan= Approach Target [08:10:47:202 I] [FollowRouteGoal] Found target! [08:10:49:908 I] [GoapAgent ] New Plan= Pull Target [08:10:50:087 I] [PullTargetGoal ] Stop auto interact! [08:10:50:968 I] [Penance ] castbar input 62ms -191.5269ms [08:10:51:249 I] [Penance ] castbar input 72ms -208.2563ms [08:10:51:265 I] [GoapAgent ] New Plan= Combat [08:10:52:610 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:10:54:908 I] [Power Word: Shield] instant input 61ms CAST_SUCCESS -207.9217ms [08:10:55:202 I] [Power Word: Shield] instant input 74ms SPELL_FAILED_NOT_READY -219.2929ms [08:10:56:087 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:10:57:005 I] [Shadow Word: Pain] instant input 63ms CAST_SUCCESS -193.0114ms [08:10:57:253 I] [Shadow Word: Pain] instant input 57ms SPELL_FAILED_NOT_READY -189.9329ms [08:10:58:160 I] [Homunculi ] ... BeforeCastDelay 500ms [08:10:59:083 I] [Homunculi ] instant input 61ms CAST_SUCCESS -192.5841ms [08:10:59:338 I] [Homunculi ] instant input 61ms SPELL_FAILED_NOT_READY -193.1172ms [08:11:00:240 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:01:640 I] [Shoot ] instant input 62ms CAST_START -209.1061ms [08:11:01:923 I] [Shoot ] instant input 75ms CAST_START -206.1388ms [08:11:03:246 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:04:678 I] [Shoot ] instant usable: True->True | current: False->True | CAST_SUCCESS->CAST_START [08:11:04:957 I] [Shoot ] instant input 76ms CAST_START -202.4861ms [08:11:05:417 I] [CombatGoal ] Lost target! [08:11:05:430 W] [GoapAgent ] New Plan= NO PLAN [08:11:05:872 I] [GoapAgent ] New Plan= Follow Route [08:11:05:876 I] [Navigation ] [LocalPathingApi] total distance 76.112274 > 100. Have to clear RouteToWaypoint. [08:11:06:144 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:06:670 I] [FollowRouteGoal] Found target! [08:11:06:752 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:06:753 I] [GoapAgent ] New Plan= Pull Target [08:11:06:973 I] [PullTargetGoal ] Stop auto interact! [08:11:07:904 I] [Penance ] castbar input 61ms -192.6928ms [08:11:08:157 I] [Penance ] castbar input 57ms -194.7601ms [08:11:08:168 I] [GoapAgent ] New Plan= Combat [08:11:09:497 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:11:10:436 I] [Shadow Word: Pain] instant input 63ms CAST_SUCCESS -208.2191ms [08:11:10:716 I] [Shadow Word: Pain] instant input 74ms SPELL_FAILED_NOT_READY -204.5801ms [08:11:11:606 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:12:965 I] [Shoot ] instant input 61ms CAST_START -188.9791ms [08:11:13:250 I] [Shoot ] instant input 77ms CAST_START -206.1773ms [08:11:14:596 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:16:008 I] [Shoot ] instant usable: True->True | current: False->True | CAST_SUCCESS->CAST_START [08:11:16:261 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:11:17:602 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:11:19:887 I] [Power Word: Shield] instant input 63ms CAST_SUCCESS -195.7184ms [08:11:20:167 I] [Power Word: Shield] instant input 73ms SPELL_FAILED_NOT_READY -205.4913ms [08:11:20:181 I] [GoapAgent ] New Plan= Follow Route [08:11:20:182 I] [Navigation ] [LocalPathingApi] total distance 60.70098 > 100. Have to clear RouteToWaypoint. [08:11:20:184 I] [FollowRouteGoal] Has target but its dead. [08:11:20:460 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:20:930 I] [FollowRouteGoal] Found target! [08:11:21:045 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:21:045 I] [GoapAgent ] New Plan= Approach Target [08:11:22:656 I] [GoapAgent ] New Plan= Pull Target [08:11:22:861 I] [PullTargetGoal ] Stop auto interact! [08:11:26:520 I] [GoapAgent ] New Plan= Combat [08:11:26:725 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:11:27:670 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | SPELL_FAILED_NOT_READY->CAST_SUCCESS [08:11:27:950 I] [Shadow Word: Pain] instant input 70ms SPELL_FAILED_NOT_READY -209.0374ms [08:11:28:829 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:30:195 I] [Shoot ] instant input 63ms CAST_START -192.8144ms [08:11:30:461 I] [Shoot ] instant input 59ms CAST_START -206.4854ms [08:11:31:799 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:33:216 I] [Shoot ] instant usable: True->True | current: False->True | CAST_SUCCESS->CAST_START [08:11:33:469 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:11:34:812 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:36:227 I] [Shoot ] instant usable: True->True | current: False->True | CAST_SUCCESS->CAST_START [08:11:36:508 I] [Shoot ] instant input 73ms CAST_START -206.6822ms [08:11:36:976 W] [GoapAgent ] New Plan= NO PLAN [08:11:37:862 I] [GoapAgent ] New Plan= Follow Route [08:11:37:863 I] [Navigation ] [LocalPathingApi] total distance 60.70098 > 100. Have to clear RouteToWaypoint. [08:11:37:865 I] [FollowRouteGoal] Has target but its dead. [08:11:38:164 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:41:043 I] [NpcNameFinder] type = None | mode = Fuzzy [08:11:41:044 I] [GoapAgent ] New Plan= Pull Target [08:11:41:092 I] [FollowRouteGoal] Found target! [08:11:41:298 I] [PullTargetGoal ] Stop auto interact! [08:11:42:243 I] [Penance ] castbar input 60ms -188.6916ms [08:11:42:529 I] [Penance ] castbar input 77ms -208.0953ms [08:11:42:542 I] [GoapAgent ] New Plan= Combat [08:11:43:868 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:11:44:796 I] [Shadow Word: Pain] instant input 63ms CAST_SUCCESS -196.7094ms [08:11:45:075 I] [Shadow Word: Pain] instant input 89ms SPELL_FAILED_NOT_READY -188.0634ms [08:11:45:961 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:47:360 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:11:47:639 I] [Shoot ] instant input 75ms CAST_START -202.8502ms [08:11:48:967 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:50:357 I] [Shoot ] instant input 61ms CAST_START -195.9088ms [08:11:50:636 I] [Shoot ] instant input 85ms CAST_START -193.686ms [08:11:51:968 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:11:54:265 I] [Power Word: Shield] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:11:54:533 I] [Power Word: Shield] instant input 60ms SPELL_FAILED_NOT_READY -206.5788ms [08:11:55:191 I] [Shoot ] ... BeforeCastDelay 1000ms [08:11:56:557 I] [Shoot ] instant input 63ms CAST_START -191.3076ms [08:11:56:848 I] [Shoot ] instant input 78ms CAST_START -211.1887ms [08:11:57:155 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:11:57:292 I] [GoapAgent ] New Plan= Consume Corpse [08:11:57:293 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:11:57:308 I] [GoapAgent ] New Plan= Loot [08:11:57:735 I] [LootGoal ] Lost target -422.9007ms [08:11:57:737 I] [LootGoal ] Target Last Target Found! [08:11:58:695 I] [BagChangeTracker] + 1 Disciple's Boots [08:11:58:726 I] [LootGoal ] Loot Successful 727.0004ms [08:11:58:739 I] [GoapAgent ] New Plan= Corpse Consumed [08:11:58:740 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:11:58:755 I] [GoapAgent ] New Plan= Follow Route [08:11:58:757 I] [Navigation ] [LocalPathingApi] total distance 0 > 100. Have to clear RouteToWaypoint. [08:11:59:043 I] [NpcNameFinder] type = None | mode = Fuzzy [08:12:00:965 I] [FollowRouteGoal] RefillWaypoints - findClosest:False - ThereAndBack:False [08:12:03:928 I] [FollowRouteGoal] Random jump [08:12:09:739 I] [NpcNameFinder] type = None | mode = Fuzzy [08:12:09:835 I] [NpcNameFinder] type = None | mode = Fuzzy [08:12:14:260 I] [BagChangeTracker] + 1 Billy Club [08:12:21:704 I] [BagChangeTracker] + 1 Burnt Leather Boots [08:12:29:687 E] Specified argument was out of the range of valid values.

Xian55 commented 5 months ago

Hello please upload the unredacted log file. In order to see the full picture of the events. Key info are logged during the startup

thejefu commented 5 months ago

Here's a full out. Believe there was an error I corrected in the .json somewhere.

The relevant combat test is done at the end where I believe 7 or 8 mobs were killed. All but the second to last mob were looted.

Hope this helps.

[08:23:25:449 I] [Program ] en 15/02/2024 08:23:25 +00:00 [08:23:25:699 I] [Program ] Pid: 7200 [08:23:25:700 I] [Program ] Version: 1.15.1.53247 [08:23:25:703 I] [Program ] Addon version: 1.7.52 [08:23:25:780 I] [Lifetime ] Now listening on: http://localhost:5000 [08:23:25:780 I] [Lifetime ] Application started. Press Ctrl+C to shut down. [08:23:25:782 I] [Lifetime ] Hosting environment: Development [08:23:25:782 I] [Lifetime ] Content root path: D:\WoWBot\BlazorServer

[08:23:53:123 I] [Program ] en 15/02/2024 08:23:53 +00:00 [08:23:53:451 I] [Program ] Pid: 7200 [08:23:53:452 I] [Program ] Version: 1.15.1.53247 [08:23:53:511 I] [Program ] Addon version: 1.7.52 [08:23:53:704 I] [Lifetime ] Now listening on: http://localhost:5000 [08:23:53:706 I] [Lifetime ] Application started. Press Ctrl+C to shut down. [08:23:53:707 I] [Lifetime ] Hosting environment: Development [08:23:53:707 I] [Lifetime ] Content root path: D:\WoWBot\BlazorServer

[08:23:58:719 W] [Program ] RemoteV3 not available! [08:23:58:724 I] [PPatherService ] MPQ files exist. [08:23:58:724 I] [Program ] Using Local(LocalPathingApi) [08:23:58:854 D] [WowScreenDXGI ] DataFrames 102 - Texture: Size [ Width=237, Height=1 ] [08:23:58:864 I] [WowScreenDXGI ] Rectangle [ X=72, Y=32, Width=1776, Height=999 ] - Windowed Mode: True - Scale: 1.00 - Monitor Rect: Left: 0, Top: 0, Right: 1920, Bottom: 1080 - Monitor Index: 0 [08:23:58:868 I] [Program ] WowScreenDXGI [08:23:59:310 I] [Program ] NoScreenCapture [08:23:59:335 I] [BotController ] Undead Priest! [08:24:01:437 I] [ExecGameCommand] /testflush [08:24:06:440 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:24:06:442 I] [Interact ] Non Actionbar I -> I [08:24:06:443 I] [InteractMouseOver] Non Actionbar J -> J [08:24:06:443 I] [Approach ] Non Actionbar I -> I [08:24:06:444 I] [AutoAttack ] Non Actionbar I -> I [08:24:06:445 I] [TargetLastTarget] Non Actionbar G -> G [08:24:06:445 I] [StandUp ] Non Actionbar X -> X [08:24:06:446 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:24:06:447 I] [StopAttack ] Non Actionbar Delete -> Delete [08:24:06:448 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:24:06:449 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:24:06:449 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:24:06:453 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:24:06:453 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:24:06:454 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:24:06:455 I] [Mount ] Non Actionbar O -> O [08:24:06:456 I] [Pull] Init Binds(Cost, Cooldown) [08:24:06:457 I] [Combat] Init Binds(Cost, Cooldown) [08:24:06:458 I] [Adhoc] Init Binds(Cost, Cooldown) [08:24:06:459 I] [Parallel] Init Binds(Cost, Cooldown) [08:24:06:463 I] [WaitKeyActions] Added Drink Buff to await Drink [08:24:06:464 I] [Pull] Init KeyActions [08:24:06:468 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:24:06:472 I] [Penance ] Requirement: "Mana%>50" [08:24:06:474 I] [Combat] Init KeyActions [08:24:06:475 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:24:06:475 I] [Renew ] Requirement: "Health%<40" [08:24:06:476 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:24:06:476 I] [Lesser Heal ] Requirement: "Health%<40" [08:24:06:477 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:24:06:478 I] [Power Word: Shield] Requirement: "!Shield" [08:24:06:478 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:24:06:479 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:24:06:480 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:24:06:482 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:24:06:485 I] [Mind Blast ] Requirement: "MobCount>1" [08:24:06:486 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:24:06:486 I] [Adhoc] Init KeyActions [08:24:06:487 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:24:06:488 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:24:06:489 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:24:06:489 I] [Inner Fire ] Requirement: "not Inner Fire" [08:24:06:490 I] [Parallel] Init KeyActions [08:24:06:492 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:24:06:493 I] [Drink ] Requirement: "Mana%<30" [08:24:06:494 I] [Drink ] Requirement: "!Drink" [08:24:06:495 I] [Drink ] Requirement: "!Swimming" [08:24:06:496 I] [Drink ] Requirement: "!Falling" [08:24:06:496 I] [Wait] Init KeyActions [08:24:06:497 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:24:06:502 I] [Drink Buff ] Requirement: "Drink" [08:24:06:504 I] [Drink Buff ] Requirement: "Mana% < 100" [08:24:06:506 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Draenei\1-5_Azuremyst Isle.json [08:24:06:517 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:24:06:540 I] [BotController ] Elapsed time: 128.3234 ms [08:24:13:462 I] [Jump ] Non Actionbar Spacebar -> Spacebar [08:24:13:463 I] [Interact ] Non Actionbar I -> I [08:24:13:464 I] [InteractMouseOver] Non Actionbar J -> J [08:24:13:465 I] [Approach ] Non Actionbar I -> I [08:24:13:466 I] [AutoAttack ] Non Actionbar I -> I [08:24:13:466 I] [TargetLastTarget] Non Actionbar G -> G [08:24:13:467 I] [StandUp ] Non Actionbar X -> X [08:24:13:467 I] [ClearTarget ] Non Actionbar Insert -> Insert [08:24:13:469 I] [StopAttack ] Non Actionbar Delete -> Delete [08:24:13:470 I] [TargetNearestTarget] Non Actionbar Tab -> Tab [08:24:13:471 I] [TargetTargetOfTarget] Non Actionbar F -> F [08:24:13:471 I] [TargetPet ] Non Actionbar Multiply -> Multiply [08:24:13:472 I] [PetAttack ] Non Actionbar Subtract -> Subtract [08:24:13:473 I] [TargetFocus ] Non Actionbar PageUp -> PageUp [08:24:13:474 I] [FollowTarget ] Non Actionbar PageDown -> PageDown [08:24:13:475 I] [Mount ] Non Actionbar O -> O [08:24:13:476 I] [Pull] Init Binds(Cost, Cooldown) [08:24:13:476 I] [Combat] Init Binds(Cost, Cooldown) [08:24:13:477 I] [Adhoc] Init Binds(Cost, Cooldown) [08:24:13:477 I] [Parallel] Init Binds(Cost, Cooldown) [08:24:13:478 I] [WaitKeyActions] Added Drink Buff to await Drink [08:24:13:480 I] [Pull] Init KeyActions [08:24:13:480 I] [Penance ] Actionbar Key:2 -> Actionbar:2 -> Index:1 [08:24:13:481 I] [Penance ] Requirement: "Mana%>50" [08:24:13:482 I] [Combat] Init KeyActions [08:24:13:483 I] [Renew ] Actionbar Key:8 -> Actionbar:8 -> Index:7 [08:24:13:483 I] [Renew ] Requirement: "Health%<40" [08:24:13:485 I] [Lesser Heal ] Actionbar Key:9 -> Actionbar:9 -> Index:8 [08:24:13:492 I] [Lesser Heal ] Requirement: "Health%<40" [08:24:13:492 I] [Power Word: Shield] Actionbar Key:5 -> Actionbar:5 -> Index:4 [08:24:13:493 I] [Power Word: Shield] Requirement: "!Shield" [08:24:13:493 I] [Shadow Word: Pain] Actionbar Key:4 -> Actionbar:4 -> Index:3 [08:24:13:494 I] [Shadow Word: Pain] Requirement: "!Shadow Word: Pain" [08:24:13:495 I] [Homunculi ] Actionbar Key:3 -> Actionbar:3 -> Index:2 [08:24:13:495 I] [Mind Blast ] Actionbar Key:6 -> Actionbar:6 -> Index:5 [08:24:13:496 I] [Mind Blast ] Requirement: "MobCount>1" [08:24:13:496 I] [Shoot ] Actionbar Key:1 -> Actionbar:1 -> Index:0 [08:24:13:497 I] [Adhoc] Init KeyActions [08:24:13:497 I] [Power Word: Fortitude] Actionbar Key:7 -> Actionbar:7 -> Index:6 [08:24:13:498 I] [Power Word: Fortitude] Requirement: "not Fortitude" [08:24:13:500 I] [Inner Fire ] Actionbar Key:= -> Actionbar:12 -> Index:11 [08:24:13:502 I] [Inner Fire ] Requirement: "not Inner Fire" [08:24:13:503 I] [Parallel] Init KeyActions [08:24:13:503 I] [Drink ] Actionbar Key:- -> Actionbar:11 -> Index:10 [08:24:13:504 I] [Drink ] Requirement: "Mana%<30" [08:24:13:504 I] [Drink ] Requirement: "!Drink" [08:24:13:505 I] [Drink ] Requirement: "!Swimming" [08:24:13:506 I] [Drink ] Requirement: "!Falling" [08:24:13:507 I] [Wait] Init KeyActions [08:24:13:508 W] [Drink Buff ] has no valid Key= or ConsoleKey=None [08:24:13:508 I] [Drink Buff ] Requirement: "Drink" [08:24:13:509 I] [Drink Buff ] Requirement: "Mana% < 100" [08:24:13:509 I] [BotController ] ClassConfig: Priest_Jeff3.json with Path: _pack\1-20\Undead\11-14_Tirisfal Glades_Balnir Farmstead.json [08:24:13:512 D] [GoapAgent ] Thread stopped! [08:24:13:512 D] [GrindSessionHandler] Thread stopped! [08:24:13:513 D] [Navigation ] Thread stopped! [08:24:13:513 D] [Navigation ] Thread stopped! [08:24:13:513 D] [FollowRouteGoal] LookingForTarget Thread stopped! [08:24:13:514 I] [TargetBlacklist] TargetMask: Normal, Trivial, Rare [08:24:13:532 I] [BotController ] Elapsed time: 71.8325 ms [08:24:25:009 I] [GoapAgent ] Inactive, kill credit detected! [08:24:27:499 I] [BagChangeTracker] + 1 Scroll: LOWER PING WHOMEVER [08:25:13:660 E] [BotController ] Unexpected character encountered while parsing value: [. Path 'Combat.Sequence[3].Requirement', line 48, position 26. Newtonsoft.Json.JsonReaderException: Unexpected character encountered while parsing value: [. Path 'Combat.Sequence[3].Requirement', line 48, position 26. at Newtonsoft.Json.JsonTextReader.ReadStringValue(ReadType readType) at Newtonsoft.Json.JsonReader.ReadForType(JsonContract contract, Boolean hasConverter) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateList(IList list, JsonReader reader, JsonArrayContract contract, JsonProperty containerProperty, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateList(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, Object existingValue, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.Deserialize(JsonReader reader, Type objectType, Boolean checkAdditionalContent) at Newtonsoft.Json.JsonSerializer.DeserializeInternal(JsonReader reader, Type objectType) at Newtonsoft.Json.JsonSerializer.Deserialize(JsonReader reader, Type objectType) at Newtonsoft.Json.JsonConvert.DeserializeObject(String value, Type type, JsonSerializerSettings settings) at Newtonsoft.Json.JsonConvert.DeserializeObject[T](String value, JsonSerializerSettings settings) at Newtonsoft.Json.JsonConvert.DeserializeObject[T](String value) at Core.BotController.ReadClassConfiguration(String classFile) in D:\WoWBot\Core\BotController.cs:line 364 at Core.BotController.InitialiseFromFile(String classFile, String pathFile) in D:\WoWBot\Core\BotController.cs:line 303 [08:25:15:041 E] [BotController ] Unexpected character encountered while parsing value: [. Path 'Combat.Sequence[3].Requirement', line 48, position 26. Newtonsoft.Json.JsonReaderException: Unexpected character encountered while parsing value: [. Path 'Combat.Sequence[3].Requirement', line 48, position 26. at Newtonsoft.Json.JsonTextReader.ReadStringValue(ReadType readType) at Newtonsoft.Json.JsonReader.ReadForType(JsonContract contract, Boolean hasConverter) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateList(IList list, JsonReader reader, JsonArrayContract contract, JsonProperty containerProperty, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateList(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, Object existingValue, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.PopulateObject(Object newObject, JsonReader reader, JsonObjectContract contract, JsonProperty member, String id) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateObject(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.CreateValueInternal(JsonReader reader, Type objectType, JsonContract contract, JsonProperty member, JsonContainerContract containerContract, JsonProperty containerMember, Object existingValue) at Newtonsoft.Json.Serialization.JsonSerializerInternalReader.Deserialize(JsonReader reader, Type objectType, Boolean checkAdditionalContent) at Newtonsoft.Json.JsonSerializer.DeserializeInternal(JsonReader reader, Type objectType) at Newtonsoft.Json.JsonSerializer.Deserialize(JsonReader reader, Type objectType) at Newtonsoft.Json.JsonConvert.DeserializeObject(String value, Type type, JsonSerializerSettings settings) at Newtonsoft.Json.JsonConvert.DeserializeObject[T](String value, JsonSerializerSettings settings) at Newtonsoft.Json.JsonConvert.DeserializeObject[T](String value) at Core.BotController.ReadClassConfiguration(String classFile) in D:\WoWBot\Core\BotController.cs:line 364 at Core.BotController.InitialiseFromFile(String classFile, String pathFile) in D:\WoWBot\Core\BotController.cs:line 303 [08:25:17:995 I] [GoapAgent ] New Plan= Adhoc [08:25:18:194 I] [Inner Fire ] instant usable: True->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:25:18:322 I] [Inner Fire ] instant input 90ms CAST_SUCCESS -31.903ms [08:25:18:333 I] [GoapAgent ] New Plan= Follow Route [08:25:18:337 I] [FollowRouteGoal] RefillWaypoints - findClosest:True - ThereAndBack:False [08:25:18:491 I] [FollowRouteGoal] RefillWaypoints - findClosest:False - ThereAndBack:False [08:25:18:908 I] [FollowRouteGoal] Found target! [08:25:19:014 I] [NpcNameFinder] type = None | mode = Fuzzy [08:25:19:017 I] [GoapAgent ] New Plan= Pull Target [08:25:19:093 I] [PullTargetGoal ] Stop auto interact! [08:25:19:564 I] [Penance ] castbar input 58ms -63.9694ms [08:25:19:786 I] [Penance ] ... casting: True | count:7 | usable: False->False | CAST_SUCCESS->CAST_SUCCESS [08:25:19:796 I] [GoapAgent ] New Plan= Combat [08:25:21:401 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:25:23:550 I] [Power Word: Shield] instant input 62ms CAST_SUCCESS -65.0498ms [08:25:23:696 I] [Power Word: Shield] instant input 76ms SPELL_FAILED_NOT_READY -68.4237ms [08:25:24:732 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:25:25:424 I] [Shadow Word: Pain] instant input 58ms SPELL_FAILED_NOT_READY -68.0768ms [08:25:25:580 I] [Shadow Word: Pain] instant input 74ms SPELL_FAILED_NOT_READY -80.8703ms [08:25:26:738 I] [Shoot ] instant input 62ms SPELL_FAILED_NOT_READY -64.1348ms [08:25:26:911 I] [Shoot ] instant input 75ms CAST_START -96.0995ms [08:25:27:003 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:25:27:725 I] [Shadow Word: Pain] instant input 76ms SPELL_FAILED_NOT_READY -81.4313ms [08:25:27:882 I] [Shadow Word: Pain] instant input 76ms SPELL_FAILED_NOT_READY -80.0113ms [08:25:28:456 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:25:29:189 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | SPELL_FAILED_NOT_READY->CAST_SUCCESS [08:25:29:351 I] [Shadow Word: Pain] instant input 75ms SPELL_FAILED_NOT_READY -84.5113ms [08:25:30:468 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:25:30:532 I] [Shoot ] instant input 62ms CAST_START 0ms [08:25:31:128 I] [Shoot ] instant input 78ms SPELL_FAILED_NOT_READY -77.9126ms [08:25:31:268 I] [Shoot ] instant input 60ms SPELL_FAILED_NOT_READY -78.4637ms [08:25:32:291 I] [Shoot ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:25:32:447 I] [Shoot ] instant input 74ms CAST_START -81.2627ms [08:25:34:048 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:25:34:207 I] [Shoot ] instant input 77ms CAST_START -80.7402ms [08:25:35:741 I] [Shoot ] instant input 59ms CAST_START -62.2241ms [08:25:35:868 I] [Shoot ] instant usable: False->False | current: True->False | CAST_START->CAST_START [08:25:36:407 I] [Shoot ] instant input 75ms SPELL_FAILED_NOT_READY -81.7244ms [08:25:36:534 I] [Shoot ] instant input 59ms SPELL_FAILED_NOT_READY -66.4824ms [08:25:36:852 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:25:36:927 I] [GoapAgent ] New Plan= Consume Corpse [08:25:36:928 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:25:36:942 I] [GoapAgent ] New Plan= Loot [08:25:37:322 I] [LootGoal ] Lost target -375.7593ms [08:25:37:323 W] [LootGoal ] Inventory is full [08:25:37:325 I] [LootGoal ] Target Last Target Found! [08:25:38:093 I] [LootGoal ] Loot Failed 640.3328ms [08:25:38:101 I] [GoapAgent ] New Plan= Corpse Consumed [08:25:38:102 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:25:38:117 W] [GoapAgent ] New Plan= NO PLAN [08:25:38:194 I] [GoapAgent ] New Plan= Follow Route [08:25:38:198 I] [Navigation ] [LocalPathingApi] distance is close 20.653105. Keep RouteToWaypoint. [08:25:38:337 I] [NpcNameFinder] type = None | mode = Fuzzy [08:25:41:654 I] [NpcNameFinder] type = None | mode = Fuzzy [08:25:41:655 I] [GoapAgent ] New Plan= Pull Target [08:25:41:749 I] [PullTargetGoal ] Stop auto interact! [08:25:41:784 I] [FollowRouteGoal] Found target! [08:25:42:223 I] [Penance ] ... casting: True | count:8 | usable: True->False | SPELL_FAILED_NOT_READY->CAST_SUCCESS [08:25:42:365 I] [Penance ] castbar input 60ms -80.8817ms [08:25:42:378 I] [GoapAgent ] New Plan= Combat [08:25:43:820 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:25:44:539 I] [Shadow Word: Pain] instant input 61ms CAST_SUCCESS -78.9836ms [08:25:44:695 I] [Shadow Word: Pain] instant input 74ms SPELL_FAILED_NOT_READY -80.6971ms [08:25:45:872 I] [Shoot ] instant input 63ms CAST_START -64.3489ms [08:25:46:049 I] [Shoot ] instant input 77ms CAST_START -98.3048ms [08:25:46:250 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:25:48:427 I] [Power Word: Shield] instant usable: False->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:25:48:585 I] [Power Word: Shield] instant input 78ms SPELL_FAILED_NOT_READY -78.9562ms [08:25:49:781 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:25:49:834 I] [Shoot ] instant input 52ms CAST_START 0ms [08:25:51:607 I] [Shoot ] instant input 62ms CAST_SUCCESS -72.38ms [08:25:51:662 I] [Shoot ] instant input 53ms CAST_START 0ms [08:25:52:259 I] [Shoot ] instant input 75ms SPELL_FAILED_NOT_READY -79.8538ms [08:25:52:418 I] [Shoot ] instant input 76ms SPELL_FAILED_NOT_READY -81.5561ms [08:25:53:459 I] [Shoot ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:25:53:618 I] [Shoot ] instant input 74ms CAST_START -83.587ms [08:25:55:316 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:25:55:455 I] [Shoot ] instant input 65ms CAST_START -72.856ms [08:25:56:333 W] [GoapAgent ] New Plan= NO PLAN [08:25:57:275 I] [GoapAgent ] New Plan= Follow Route [08:25:57:276 I] [Navigation ] [LocalPathingApi] distance is close 23.781013. Keep RouteToWaypoint. [08:25:57:277 I] [FollowRouteGoal] Has target but its dead. [08:25:57:447 I] [NpcNameFinder] type = None | mode = Fuzzy [08:25:58:494 I] [NpcNameFinder] type = None | mode = Fuzzy [08:25:58:495 I] [GoapAgent ] New Plan= Approach Target [08:25:58:578 I] [FollowRouteGoal] Found target! [08:26:01:413 I] [GoapAgent ] New Plan= Pull Target [08:26:01:491 I] [PullTargetGoal ] Stop auto interact! [08:26:01:965 I] [Penance ] castbar input 77ms -84.6678ms [08:26:02:126 I] [Penance ] castbar input 87ms -71.877ms [08:26:02:130 I] [GoapAgent ] New Plan= Combat [08:26:03:561 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:26:04:286 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:26:04:457 I] [Shadow Word: Pain] instant input 75ms SPELL_FAILED_NOT_READY -94.3421ms [08:26:05:586 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:26:05:742 I] [Shoot ] instant input 77ms CAST_START -77.8352ms [08:26:07:457 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:26:07:521 I] [Shoot ] instant input 62ms CAST_START 0ms [08:26:08:088 I] [Shoot ] instant input 62ms SPELL_FAILED_NOT_READY -65.5204ms [08:26:08:246 I] [Shoot ] instant input 74ms SPELL_FAILED_NOT_READY -82.3217ms [08:26:09:216 I] [Shoot ] instant input 63ms SPELL_FAILED_NOT_READY -63.2618ms [08:26:09:344 I] [Shoot ] instant usable: False->False | current: True->True | SPELL_FAILED_NOT_READY->CAST_START [08:26:09:908 I] [Shoot ] instant input 89ms SPELL_FAILED_NOT_READY -79.9403ms [08:26:10:063 I] [Shoot ] instant input 75ms SPELL_FAILED_NOT_READY -79.344ms [08:26:11:042 I] [Shoot ] instant input 62ms CAST_START -65.4271ms [08:26:11:199 I] [Shoot ] instant input 91ms CAST_START -65.3938ms [08:26:11:731 I] [Shoot ] instant input 76ms SPELL_FAILED_NOT_READY -78.8947ms [08:26:11:879 I] [Shoot ] instant input 75ms SPELL_FAILED_NOT_READY -71.5946ms [08:26:11:885 W] [GoapAgent ] New Plan= NO PLAN [08:26:12:199 I] [GoapAgent ] New Plan= Follow Route [08:26:12:199 I] [Navigation ] [LocalPathingApi] distance is close 5.4447975. Keep RouteToWaypoint. [08:26:12:201 I] [FollowRouteGoal] Has target but its dead. [08:26:12:339 I] [NpcNameFinder] type = None | mode = Fuzzy [08:26:17:248 I] [FollowRouteGoal] Random jump [08:26:18:442 I] [NpcNameFinder] type = None | mode = Fuzzy [08:26:18:442 I] [GoapAgent ] New Plan= Approach Target [08:26:18:528 I] [FollowRouteGoal] Found target! [08:26:19:128 I] [GoapAgent ] New Plan= Pull Target [08:26:19:207 I] [PullTargetGoal ] Stop auto interact! [08:26:19:695 I] [Penance ] castbar input 78ms -80.4033ms [08:26:19:934 I] [Penance ] castbar input 60ms -64.4333ms [08:27:09:250 I] [BagChangeTracker] + 9 Linen Cloth [08:28:26:384 I] [BagChangeTracker] 9 -> 10 Linen Cloth [08:29:22:044 I] [BagChangeTracker] + 1 Patchwork Shoes [08:29:26:059 I] [BagChangeTracker] - 1 Scroll: LOWER PING WHOMEVER [08:30:59:178 I] [GoapAgent ] New Plan= Follow Route [08:30:59:179 I] [Navigation ] [LocalPathingApi] distance from nearlest point is 58.25011. Have to clear RouteToWaypoint. [08:30:59:378 I] [NpcNameFinder] type = None | mode = Fuzzy [08:30:59:631 I] [Navigation ] Pathfinder - 58.09585 - <2062.2007, -484.57715, 0> -> <2004.9498, -474.7046, 0> 381.3801ms [08:31:04:258 I] [FollowRouteGoal] Random jump [08:31:09:580 I] [NpcNameFinder] type = None | mode = Fuzzy [08:31:09:580 I] [GoapAgent ] New Plan= Approach Target [08:31:09:616 I] [FollowRouteGoal] Found target! [08:31:11:706 I] [GoapAgent ] New Plan= Pull Target [08:31:11:787 I] [PullTargetGoal ] Stop auto interact! [08:31:12:191 I] [Penance ] castbar input 61ms -63.168ms [08:31:12:457 I] [Penance ] castbar input 78ms -94.7453ms [08:31:12:472 I] [GoapAgent ] New Plan= Combat [08:31:13:873 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:31:16:037 I] [Power Word: Shield] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:31:16:163 I] [Power Word: Shield] instant input 58ms SPELL_FAILED_NOT_READY -65.7336ms [08:31:17:213 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:31:17:940 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | SPELL_FAILED_NOT_READY->CAST_SUCCESS [08:31:18:096 I] [Shadow Word: Pain] instant input 76ms SPELL_FAILED_NOT_READY -78.5267ms [08:31:19:050 I] [Homunculi ] ... BeforeCastDelay 500ms [08:31:19:759 I] [Homunculi ] instant input 63ms CAST_SUCCESS -63.6899ms [08:31:19:919 I] [Homunculi ] instant input 77ms SPELL_FAILED_NOT_READY -81.5979ms [08:31:21:015 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:31:21:136 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:31:21:655 I] [Shoot ] instant input 65ms SPELL_FAILED_NOT_READY -66.7326ms [08:31:21:780 I] [Shoot ] instant input 57ms SPELL_FAILED_NOT_READY -66.8867ms [08:31:22:785 I] [Shoot ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:31:22:847 I] [Shoot ] instant input 61ms CAST_START 0ms [08:31:24:585 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:31:24:742 I] [Shoot ] instant input 76ms CAST_START -79.8122ms [08:31:26:385 I] [Shoot ] instant input 57ms CAST_SUCCESS -68.5747ms [08:31:26:511 I] [Shoot ] instant usable: False->False | current: True->False | CAST_SUCCESS->CAST_START [08:31:26:976 I] [Shoot ] instant input 93ms CAST_SUCCESS 0ms [08:31:27:796 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:31:27:809 I] [GoapAgent ] New Plan= Consume Corpse [08:31:27:810 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:31:27:825 I] [GoapAgent ] New Plan= Loot [08:31:27:888 I] [LootGoal ] Lost target 62.8073ms [08:31:28:013 I] [LootGoal ] Target Last Target Found! [08:31:28:950 I] [LootGoal ] Loot Successful 844.2142ms [08:31:28:966 I] [GoapAgent ] New Plan= Corpse Consumed [08:31:28:966 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:31:28:982 W] [GoapAgent ] New Plan= NO PLAN [08:31:29:092 I] [GoapAgent ] New Plan= Follow Route [08:31:29:092 I] [Navigation ] [LocalPathingApi] distance is close 3.720426. Keep RouteToWaypoint. [08:31:29:267 I] [NpcNameFinder] type = None | mode = Fuzzy [08:31:32:044 I] [NpcNameFinder] type = None | mode = Fuzzy [08:31:32:044 I] [GoapAgent ] New Plan= Pull Target [08:31:32:126 I] [PullTargetGoal ] Stop auto interact! [08:31:32:156 I] [FollowRouteGoal] Found target! [08:31:32:559 I] [Penance ] castbar input 58ms -62.4309ms [08:31:32:793 I] [Penance ] castbar input 62ms -78.8007ms [08:31:32:808 I] [GoapAgent ] New Plan= Combat [08:31:34:236 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:31:34:963 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:31:35:130 I] [Shadow Word: Pain] instant input 77ms SPELL_FAILED_NOT_READY -88.7686ms [08:31:36:248 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:31:36:376 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:31:38:030 I] [Shoot ] instant input 62ms CAST_SUCCESS -63.7456ms [08:31:38:092 I] [Shoot ] instant input 61ms CAST_START 0ms [08:31:38:697 I] [Shoot ] instant input 78ms SPELL_FAILED_NOT_READY -79.281ms [08:31:38:860 I] [Shoot ] instant input 78ms SPELL_FAILED_NOT_READY -83.3232ms [08:31:39:836 I] [Shoot ] instant input 62ms SPELL_FAILED_NOT_READY -62.7422ms [08:31:39:995 I] [Shoot ] instant input 78ms CAST_START -79.2858ms [08:31:40:564 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:31:40:580 I] [GoapAgent ] New Plan= Consume Corpse [08:31:40:580 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:31:40:596 I] [GoapAgent ] New Plan= Loot [08:31:40:942 I] [LootGoal ] Lost target -345.6996ms [08:31:40:942 I] [LootGoal ] Target Last Target Found! [08:31:41:787 I] [LootGoal ] Loot Successful 719.8082ms [08:31:41:800 I] [GoapAgent ] New Plan= Corpse Consumed [08:31:41:800 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:31:41:816 I] [GoapAgent ] New Plan= Follow Route [08:31:41:816 I] [Navigation ] [LocalPathingApi] distance is close 20.037857. Keep RouteToWaypoint. [08:31:41:975 I] [FollowRouteGoal] Found target! [08:31:42:085 I] [GoapAgent ] New Plan= Pull Target [08:31:42:168 I] [PullTargetGoal ] Stop auto interact! [08:31:45:006 I] [Penance ] ... casting: True | count:13 | usable: True->False | CAST_SUCCESS->CAST_SUCCESS [08:31:45:165 I] [Penance ] castbar input 77ms -81.0178ms [08:31:45:179 I] [GoapAgent ] New Plan= Combat [08:31:46:687 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:31:47:385 I] [Shadow Word: Pain] instant input 60ms CAST_SUCCESS -67.2056ms [08:31:47:511 I] [Shadow Word: Pain] instant input 58ms SPELL_FAILED_NOT_READY -67.0244ms [08:31:48:620 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:31:48:753 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:31:48:845 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:31:51:050 I] [Power Word: Shield] instant input 61ms CAST_SUCCESS -66.8683ms [08:31:51:205 I] [Power Word: Shield] instant input 75ms SPELL_FAILED_NOT_READY -78.8385ms [08:31:52:431 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:31:52:591 I] [Shoot ] instant input 76ms CAST_START -83.1248ms [08:31:54:167 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:31:54:324 I] [Shoot ] instant input 78ms CAST_START -77.9825ms [08:31:54:531 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:31:54:544 I] [GoapAgent ] New Plan= Consume Corpse [08:31:54:545 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:31:54:560 I] [GoapAgent ] New Plan= Loot [08:31:54:923 I] [LootGoal ] Lost target -363.0001ms [08:31:54:924 I] [LootGoal ] Target Last Target Found! [08:31:55:823 I] [LootGoal ] Loot Successful 771.3319ms [08:31:55:835 I] [GoapAgent ] New Plan= Corpse Consumed [08:31:55:836 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:31:55:850 I] [GoapAgent ] New Plan= Follow Route [08:31:55:851 I] [Navigation ] [LocalPathingApi] distance is close 5.262211. Keep RouteToWaypoint. [08:31:55:977 I] [FollowRouteGoal] Found target! [08:31:56:085 I] [GoapAgent ] New Plan= Approach Target [08:31:57:124 I] [GoapAgent ] New Plan= Pull Target [08:31:57:205 I] [PullTargetGoal ] Stop auto interact! [08:31:57:639 I] [Penance ] ... casting: True | count:14 | usable: True->False | SPELL_FAILED_TARGETS_DEAD->CAST_SUCCESS [08:31:57:765 I] [Penance ] castbar input 61ms -63.2276ms [08:31:57:779 I] [GoapAgent ] New Plan= Combat [08:31:59:277 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:32:00:006 I] [Shadow Word: Pain] instant usable: True->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:32:00:130 I] [Shadow Word: Pain] instant input 61ms SPELL_FAILED_NOT_READY -62.4422ms [08:32:01:325 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:32:01:453 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:32:03:138 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:32:03:296 I] [Shoot ] instant input 76ms CAST_START -81.6749ms [08:32:04:872 I] [Shoot ] instant input 63ms CAST_SUCCESS -67.1975ms [08:32:05:030 I] [Shoot ] instant input 76ms CAST_START -81.2933ms [08:32:06:716 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:32:06:872 I] [Shoot ] instant input 75ms CAST_START -79.8023ms [08:32:08:522 I] [Shoot ] instant input 60ms CAST_SUCCESS -68.0151ms [08:32:08:679 I] [Shoot ] instant input 74ms CAST_START -82.0961ms [08:32:10:245 I] [Shoot ] instant input 63ms CAST_SUCCESS -63.3134ms [08:32:10:406 I] [Shoot ] instant input 79ms CAST_START -81.6997ms [08:32:11:990 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:32:12:087 I] [Shoot ] instant input 77ms CAST_SUCCESS -85.2849ms [08:32:12:209 I] [Shoot ] instant input 57ms NONE -64.2683ms [08:32:12:210 I] [CombatGoal ] Lost target! [08:32:12:224 I] [GoapAgent ] New Plan= Consume Corpse [08:32:12:225 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:32:12:240 I] [GoapAgent ] New Plan= Loot [08:32:12:241 I] [LootGoal ] Lost target 0.0004ms [08:32:12:371 I] [LootGoal ] Target Last Target Found! [08:32:13:224 I] [LootGoal ] Loot Successful 726.4548ms [08:32:13:386 I] [GoapAgent ] New Plan= Corpse Consumed [08:32:13:386 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:32:13:402 I] [GoapAgent ] New Plan= Follow Route [08:32:13:402 I] [Navigation ] [LocalPathingApi] distance is close 10.648579. Keep RouteToWaypoint. [08:32:13:433 I] [BagChangeTracker] 10 -> 12 Linen Cloth [08:32:13:464 I] [BagChangeTracker] + 1 Commoner's Sword [08:32:13:530 I] [NpcNameFinder] type = None | mode = Fuzzy [08:32:14:034 I] [FollowRouteGoal] Found target! [08:32:14:135 I] [NpcNameFinder] type = None | mode = Fuzzy [08:32:14:135 I] [GoapAgent ] New Plan= Approach Target [08:32:15:126 D] [ApproachTargetGoal] Stick to initial target! [08:32:15:715 I] [GoapAgent ] New Plan= Pull Target [08:32:15:796 I] [PullTargetGoal ] Stop auto interact! [08:32:16:282 I] [Penance ] ... casting: False | count:14 | usable: True->False | NONE->NONE [08:32:16:522 I] [Penance ] castbar input 56ms -67.7663ms [08:32:16:534 I] [GoapAgent ] New Plan= Combat [08:32:17:956 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:32:18:683 I] [Shadow Word: Pain] instant usable: True->False | current: False->False | CAST_SUCCESS->CAST_SUCCESS [08:32:18:849 I] [Shadow Word: Pain] instant input 77ms SPELL_FAILED_NOT_READY -88.4862ms [08:32:20:027 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:32:20:154 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:32:21:853 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:32:22:005 I] [Shoot ] instant input 88ms CAST_START -62.6656ms [08:32:22:100 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:32:24:248 I] [Power Word: Shield] instant input 60ms CAST_SUCCESS -68.1462ms [08:32:24:401 I] [Power Word: Shield] instant input 72ms CAST_SUCCESS -79.7818ms [08:32:25:562 I] [Shoot ] instant input 60ms CAST_SUCCESS -65.5129ms [08:32:25:690 I] [Shoot ] instant usable: True->True | current: True->False | CAST_SUCCESS->CAST_START [08:32:26:207 I] [Shoot ] instant input 59ms SPELL_FAILED_NOT_READY -65.1894ms [08:32:26:367 I] [Shoot ] instant input 76ms SPELL_FAILED_NOT_READY -82.6138ms [08:32:27:369 I] [Shoot ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:32:27:526 I] [Shoot ] instant input 75ms CAST_START -80.4321ms [08:32:29:108 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:32:29:235 I] [Shoot ] instant usable: False->False | current: True->False | CAST_START->CAST_START [08:32:29:775 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:32:30:917 I] [Shoot ] instant input 62ms NONE -66.2019ms [08:32:31:074 I] [Shoot ] instant input 75ms NONE -81.515ms [08:32:31:088 I] [GoapAgent ] New Plan= Consume Corpse [08:32:31:088 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:32:31:103 I] [GoapAgent ] New Plan= Loot [08:32:31:458 I] [LootGoal ] Lost target -353.897ms [08:32:31:458 I] [LootGoal ] Target Last Target Found! [08:32:32:305 I] [LootGoal ] Loot Successful 727.0319ms [08:32:32:432 I] [BagChangeTracker] + 1 Short Sabre [08:32:32:444 I] [GoapAgent ] New Plan= Corpse Consumed [08:32:32:445 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:32:32:460 I] [GoapAgent ] New Plan= Follow Route [08:32:32:461 I] [Navigation ] [LocalPathingApi] distance is close 20.123257. Keep RouteToWaypoint. [08:32:32:619 I] [FollowRouteGoal] Found target! [08:32:32:727 I] [GoapAgent ] New Plan= Approach Target [08:32:33:184 D] [ApproachTargetGoal] Stick to initial target! [08:32:34:460 I] [GoapAgent ] New Plan= Pull Target [08:32:34:540 I] [PullTargetGoal ] Stop auto interact! [08:32:35:014 I] [Penance ] ... casting: True | count:16 | usable: True->False | NONE->CAST_SUCCESS [08:32:35:139 I] [Penance ] castbar input 60ms -63.7922ms [08:32:35:153 I] [GoapAgent ] New Plan= Combat [08:32:36:643 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:32:37:361 I] [Shadow Word: Pain] instant usable: True->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:32:37:524 I] [Shadow Word: Pain] instant input 87ms SPELL_FAILED_NOT_READY -74.7185ms [08:32:38:689 I] [Shoot ] instant input 63ms SPELL_FAILED_NOT_READY -64.2523ms [08:32:38:847 I] [Shoot ] instant input 92ms CAST_START -65.4113ms [08:32:40:434 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:32:40:560 I] [Shoot ] instant usable: False->False | current: True->False | CAST_START->CAST_START [08:32:41:101 I] [Shoot ] instant input 76ms SPELL_FAILED_NOT_READY -83.5828ms [08:32:41:252 I] [Shoot ] instant input 72ms SPELL_FAILED_NOT_READY -77.6712ms [08:32:42:265 I] [Shoot ] instant usable: False->False | current: False->True | SPELL_FAILED_NOT_READY->CAST_START [08:32:42:421 I] [Shoot ] instant input 73ms CAST_START -82.0861ms [08:32:43:993 I] [Shoot ] instant input 60ms CAST_SUCCESS -65.9354ms [08:32:44:168 I] [Shoot ] instant input 75ms CAST_START -99.4426ms [08:32:45:756 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:32:45:908 I] [Shoot ] instant input 87ms CAST_START -64.2675ms [08:32:46:382 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:32:47:538 I] [Shoot ] instant input 62ms NONE -65.2002ms [08:32:47:660 I] [Shoot ] instant input 58ms NONE -62.2603ms [08:32:47:661 I] [CombatGoal ] Lost target! [08:32:47:676 I] [GoapAgent ] New Plan= Consume Corpse [08:32:47:677 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:32:47:692 I] [GoapAgent ] New Plan= Loot [08:32:47:692 I] [LootGoal ] Lost target 0.0004ms [08:32:47:816 I] [LootGoal ] Target Last Target Found! [08:32:48:665 I] [LootGoal ] Loot Successful 719.2415ms [08:32:48:800 I] [BagChangeTracker] + 3 Forest Mushroom Cap [08:32:48:806 I] [GoapAgent ] New Plan= Corpse Consumed [08:32:48:807 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:32:48:822 I] [GoapAgent ] New Plan= Follow Route [08:32:48:823 I] [Navigation ] [LocalPathingApi] distance is close 32.22067. Keep RouteToWaypoint. [08:32:48:949 I] [NpcNameFinder] type = None | mode = Fuzzy [08:32:49:468 I] [FollowRouteGoal] Found target! [08:32:49:590 I] [NpcNameFinder] type = None | mode = Fuzzy [08:32:49:591 I] [GoapAgent ] New Plan= Pull Target [08:32:49:672 I] [PullTargetGoal ] Stop auto interact! [08:32:50:108 I] [Penance ] castbar input 87ms -62.8266ms [08:32:50:328 I] [Penance ] castbar input 60ms -62.676ms [08:32:50:359 I] [GoapAgent ] New Plan= Approach Target [08:32:50:718 I] [GoapAgent ] New Plan= Pull Target [08:32:50:798 I] [PullTargetGoal ] Stop auto interact! [08:32:56:237 D] [CombatUtil ] Combat Enter [08:32:56:252 I] [GoapAgent ] New Plan= Combat [08:32:56:333 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:32:58:505 I] [Power Word: Shield] instant usable: True->False | current: False->False | ERR_SPELL_OUT_OF_RANGE->CAST_SUCCESS [08:32:58:659 I] [Power Word: Shield] instant input 58ms SPELL_FAILED_NOT_READY -95.1083ms [08:32:59:603 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:33:00:302 I] [Shadow Word: Pain] instant input 63ms SPELL_FAILED_NOT_READY -66.6452ms [08:33:00:435 I] [Shadow Word: Pain] instant usable: False->False | current: True->False | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:33:01:599 I] [Shoot ] instant input 58ms SPELL_FAILED_NOT_READY -63.7284ms [08:33:01:676 I] [Shoot ] instant input 76ms CAST_START 0.0001ms [08:33:03:403 I] [Shoot ] instant input 63ms CAST_SUCCESS -63.5188ms [08:33:03:481 I] [Shoot ] instant input 78ms CAST_START 0.0001ms [08:33:04:100 I] [Shoot ] instant input 78ms SPELL_FAILED_NOT_READY -80.3202ms [08:33:04:262 I] [Shoot ] instant input 78ms SPELL_FAILED_NOT_READY -83.6569ms [08:33:05:234 I] [Shoot ] instant input 59ms SPELL_FAILED_NOT_READY -63.0611ms [08:33:05:361 I] [Shoot ] instant usable: False->False | current: True->True | SPELL_FAILED_NOT_READY->CAST_START [08:33:05:895 I] [Shoot ] instant input 89ms SPELL_FAILED_NOT_READY -63.369ms [08:33:06:051 I] [Shoot ] instant input 76ms SPELL_FAILED_NOT_READY -79.1729ms [08:33:07:047 I] [Shoot ] instant input 62ms SPELL_FAILED_NOT_READY -64.1455ms [08:33:07:208 I] [Shoot ] instant input 78ms CAST_START -82.7046ms [08:33:08:787 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:33:08:913 I] [Shoot ] instant usable: False->False | current: True->False | CAST_START->CAST_START [08:33:10:513 I] [Shoot ] instant usable: False->False | current: False->True | CAST_SUCCESS->CAST_START [08:33:10:671 I] [Shoot ] instant input 78ms CAST_START -79.5255ms [08:33:11:913 W] [GoapAgent ] New Plan= NO PLAN [08:33:12:402 I] [GoapAgent ] New Plan= Follow Route [08:33:12:402 I] [Navigation ] [LocalPathingApi] distance from nearlest point is 68.23091. Have to clear RouteToWaypoint. [08:33:12:403 I] [FollowRouteGoal] Has target but its dead. [08:33:12:592 I] [NpcNameFinder] type = None | mode = Fuzzy [08:33:12:729 I] [Navigation ] Pathfinder - 70.203476 - <2018.6729, -355.11914, 34.581898> -> <2046.1666, -419.71497, 34.581898> 24.1932ms [08:33:13:515 I] [FollowRouteGoal] Found target! [08:33:13:617 I] [NpcNameFinder] type = None | mode = Fuzzy [08:33:13:617 I] [GoapAgent ] New Plan= Approach Target [08:33:14:027 D] [ApproachTargetGoal] Found a closer target! 5 < 30 [08:33:14:042 I] [GoapAgent ] New Plan= Pull Target [08:33:14:121 I] [PullTargetGoal ] Stop auto interact! [08:33:14:606 I] [Penance ] castbar input 61ms -80.1242ms [08:33:14:860 I] [Penance ] castbar input 68ms -80.5659ms [08:33:14:874 I] [GoapAgent ] New Plan= Combat [08:33:16:320 I] [Shadow Word: Pain] ... BeforeCastDelay 500ms [08:33:17:047 I] [Shadow Word: Pain] instant usable: True->False | current: False->True | CAST_SUCCESS->CAST_SUCCESS [08:33:17:203 I] [Shadow Word: Pain] instant input 75ms SPELL_FAILED_NOT_READY -79.9396ms [08:33:18:290 I] [Shoot ] instant input 61ms CAST_START -65.9478ms [08:33:18:414 I] [Shoot ] instant usable: True->True | current: True->False | CAST_START->CAST_START [08:33:18:948 I] [Shoot ] instant input 72ms SPELL_FAILED_NOT_READY -78.9733ms [08:33:19:074 I] [Shoot ] instant input 62ms SPELL_FAILED_NOT_READY -62.5427ms [08:33:19:929 I] [Homunculi ] ... BeforeCastDelay 500ms [08:33:20:619 I] [Homunculi ] instant input 62ms SPELL_FAILED_NOT_READY -64.1954ms [08:33:20:746 I] [Homunculi ] instant input 61ms SPELL_FAILED_NOT_READY -64.6753ms [08:33:21:983 I] [Shoot ] instant usable: True->True | current: False->True | SPELL_FAILED_NOT_READY->SPELL_FAILED_NOT_READY [08:33:22:046 I] [Shoot ] instant input 62ms CAST_START 0ms [08:33:23:619 I] [Power Word: Shield] ... BeforeCastDelay 2000ms [08:33:25:856 I] [Power Word: Shield] instant input 62ms CAST_SUCCESS -64.1298ms [08:33:25:983 I] [Power Word: Shield] instant input 61ms SPELL_FAILED_NOT_READY -65.4053ms [08:33:26:668 I] [GoapAgent ] Kill credit detected! Known kills: 1 | Fighting with: 0 [08:33:27:215 I] [Shoot ] instant input 77ms NONE -79.9277ms [08:33:27:373 I] [Shoot ] instant input 91ms NONE -65.4493ms [08:33:27:385 I] [GoapAgent ] New Plan= Consume Corpse [08:33:27:386 I] [ConsumeCorpseGoal] Safe to consume a corpse. [08:33:27:401 I] [GoapAgent ] New Plan= Loot [08:33:27:760 I] [LootGoal ] Lost target -358.9751ms [08:33:27:761 I] [LootGoal ] Target Last Target Found! [08:33:28:546 I] [LootGoal ] Loot Successful 638.6217ms [08:33:28:558 I] [GoapAgent ] New Plan= Corpse Consumed [08:33:28:558 I] [CorpseConsumedGoal] Total: 0 | Remaining: 0 [08:33:28:573 I] [GoapAgent ] New Plan= Follow Route [08:33:28:574 I] [Navigation ] [LocalPathingApi] distance is close 73.14843. Keep RouteToWaypoint. [08:33:28:707 I] [FollowRouteGoal] Found target! [08:33:28:808 I] [GoapAgent ] New Plan= Approach Target

Xian55 commented 5 months ago

After looking at the logs, i can see that most of the casting like [Penance ] castbar input 61ms -80.1242ms has a negative number at the end, that means the designated time were not enough to detect the action at the c# side. i've seen this kind of issue among the other opened github issues. Sadly i was unable to reproduce it, not find the source of it.

Can you tell me more about your setup?

thejefu commented 5 months ago

I play with frame rate visible and achieve consistent 160+ fps.

Max background is 30, target fps 60 and max foreground fps disabled. V sync enabled.

Happy to perform additional tests to try to isolate it.

Xian55 commented 5 months ago

If you keep alt-tabbing(switching to other applications) frequently i recommend to target the same framerate during background and foreground fps.

thejefu commented 5 months ago

I set all the FPS settings to target 75 FPS, have set the game window to a 'medium' distance of camera zoom and made sure that the client always has access to the mouse (no alt tabbing) and the problem has resolved.

Anyone still having this problem should trial and error the above.

Xian55 commented 4 months ago

With the #572 fix, the loot should be more reliable.

I've also added more verbose logging, so now its going to show up how many kills have been collected in the session.

Let me know if you still encounter the issue, it can still happen, but not that frequently.

Thank you for your patience.

adamssullivan commented 4 months ago

I will give it a shot thanks

Sattva-108 commented 2 months ago

i can confirm

  • are you playing with fix frame rate like targeting 30/60/90 frames per second, the bot should be work with any framerate however consistency is key! To have a stable framerate.

i can confirm that this has fixed my issue with him not looting properly.

i just set the "fixed" foreground FPS to 120, and he started to loot everything. before that he was just starting to run (most of the time) -> to next mob after kill.

Xian55 commented 2 months ago

On the other hand recently ive made a discovery that while vsync is enabledy could cause major latency issues. The backend unable to read all of the frames.

And the player movement becomes really zig zag ish. As it seems keep correcting itself while turning left to right.

Sattva-108 commented 2 months ago

On the other hand recently ive made a discovery that while vsync is enabledy could cause major latency issues. The backend unable to read all of the frames.

It is recommended in the README to turn off Vsync. So i did that, should be fine.

I have followed "Getting it working" And don't remember FPS step. Could i make PR to add that info?

Sattva-108 commented 2 months ago

And i also have troubles with bot being stuck after looting WHILE IN COMBAT with 2 mobs.

Scenario: Pull 1 mob, 2nd pull accidentally --> kill 1st mob, loot him, --> and mob on side just kills you, while bot is AFK.

Is it my setup wrong?

Xian55 commented 2 months ago

Hello,

I have followed "Getting it working" And don't remember FPS step. Could i make PR to add that info?

sure go ahead if you want to contribute you are more then welcome.

Regarding what FPS works the best. Well, the Backend is capable of handling any kind of FPS. However there are some limitation regarding how often the DXGI can capture frames, if i recall correctly its about 10ms according to the official documentation, however i'm unable to find the related docs at the moment.

Other than that i recommend to choose a FPS which is below what you machine can handle, and set a FRAME limiter. I think you can use the in-game frame limiter. However personally, in general, i don't trust in-game Frame limiters. For me what worked the best is "Guru3D RTSS Rivatuner Statistics Server" it provides the smoothest frame time.

I've tested the bot with the minimum FPS 8 and up to 90. Between these values are fine, but of course it depends on what your hardware can handle.


And i also have troubles with bot being stuck after looting WHILE IN COMBAT with 2 mobs. Scenario: Pull 1 mob, 2nd pull accidentally --> kill 1st mob, loot him, --> and mob on side just kills you, while bot is AFK. Is it my setup wrong?

I think i ran into similar situation when the first mob was skinnable, sadly the interact with Target feature prefers the corpse instead of the still fighting mob. It can happen sadly.

I was kind enough to answer your question here, if you encounter any kind of issue, please open a new issue, since this one is already closed and we are going off topic here.