2016-06-24 12:39:51,315 [35] WARN Logger - [CurrentMap] Boss room in this map will be ignored according to settings.
2016-06-24 12:39:51,315 [35] INFO Logger - [MapBuddy] New map has been entered: Jungle Valley Map (7)
2016-06-24 12:39:51,316 [35] DEBUG Logger - [MapBuddy][Statistics] Current map Name: Jungle Valley Map, Tier: 7
2016-06-24 12:39:51,316 [35] INFO Logger - [GridExplorer] TileKnownRadius is being set to 5.
2016-06-24 12:39:51,317 [35] INFO Logger - [GridExplorer] TileSeenRadius is being set to 4.
2016-06-24 12:39:51,317 [35] INFO Logger - [MapSpecificTweaks] Setting tile seen radius to 3.
2016-06-24 12:39:51,317 [35] INFO Logger - [GridExplorer] TileSeenRadius is being set to 3.
2016-06-24 12:39:51,318 [35] DEBUG Logger - [TrackMobTask] New map entered.
2016-06-24 12:39:51,318 [35] DEBUG Logger - [TrackMobTask] Monster cache has been cleared.
2016-06-24 12:39:51,318 [35] INFO Logger - [AreaTransitionTask] Transition cache has been cleared.
2016-06-24 12:39:51,366 [35] INFO Logger - [EnableAlwaysHiglight] Now enabling Always Highlight.
2016-06-24 12:39:51,447 [35] INFO Logger - [PostCombatHookTask] GemLevelerPro returned true.
2016-06-24 12:39:51,555 [35] INFO Logger - [WithdrawTask] We cannot withdraw items out of town/hideout. Skipping this task until an area change or restart.
2016-06-24 12:39:51,561 [35] WARN Logger - [MapExplorationTask] Explored percent: 6%. Monsters Remaining: 51. Percentage to Check: 0. Mobs to Finish: 0. Boss room will be ignored. Exploration task is not needed anymore.
2016-06-24 12:39:51,662 [35] WARN Logger - [MapExplorationCompleteTask] Now finishing the map run.
2016-06-24 12:39:51,662 [1] INFO Logger - [MapBuddy][Statistics] Map finished. 6/24/2016 10:39:51 PM. Name:Jungle Valley Map. Time: 00:00:00 (00:00:00). Mods:Ceremonial, of Lightning. Deaths: 0
2016-06-24 12:39:51,663 [35] DEBUG Logger - [CreatePortalToTown] Now opening the inventory panel.
2016-06-24 12:39:51,664 [35] DEBUG Logger - [OpenInventoryPanel]
2016-06-24 12:39:51,665 [35] DEBUG Logger - [OpenInventoryPanel] The InventoryUi is not opened. Now opening it.
2016-06-24 12:39:51,676 [35] INFO Logger - [LatencyWait] Now sleeping 58 ms.
2016-06-24 12:39:51,755 [35] INFO Logger - [ReactionWait] Now sleeping 298 ms.
2016-06-24 12:39:51,778 [35] DEBUG Logger - [ResetAnchorPoint] Setting AnchorPoint to {614, 234} for 1970039449.
2016-06-24 12:39:51,778 [35] DEBUG Logger - [ResetAnchorPoint] Setting CurrentAnchorPoint to {614, 234} for 1970039449.
2016-06-24 12:39:52,062 [35] INFO Logger - [ReactionWait] Now sleeping 285 ms.
2016-06-24 12:39:52,365 [35] DEBUG Logger - [CreatePortalToTown] Now searching the main inventory for a Portal Scroll.
2016-06-24 12:39:52,365 [35] DEBUG Logger - [CreatePortalToTown] Now using the Portal Scroll.
2016-06-24 12:39:52,397 [35] INFO Logger - [LatencyWait] Now sleeping 45 ms.
2016-06-24 12:39:52,455 [35] INFO Logger - [ReactionWait] Now sleeping 262 ms.
2016-06-24 12:39:52,731 [35] DEBUG Logger - [CloseBlockingWindows] LokiPoe.Gui.IsLeftPanelShown || LokiPoe.Gui.IsRightPanelShown. Closing them.
2016-06-24 12:39:52,764 [35] INFO Logger - [TakeClosestPortal] Now disabling Always Highlight to avoid label issues.
2016-06-24 12:39:52,797 [35] DEBUG Logger - [TakeClosestPortal] Now waiting for the portal to spawn. 00:00:00.0335946 elapsed.
2016-06-24 12:39:52,798 [35] INFO Logger - [LatencyWait] Now sleeping 45 ms.
2016-06-24 12:39:52,858 [35] DEBUG Logger - <log4net.Error>Exception during StringFormat: Index (zero based) must be greater than or equal to zero and less than the size of the argument list. <format>[TakeClosestPortal] The portal was found at {615, 234}.</format><args>{}</args></log4net.Error>
2016-06-24 12:39:52,859 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:39:52,859 [35] DEBUG Logger - <log4net.Error>Exception during StringFormat: Index (zero based) must be greater than or equal to zero and less than the size of the argument list. <format>[TakeClosestPortal] The portal to interact with is 949 at {615, 234}.</format><args>{}</args></log4net.Error>
2016-06-24 12:39:52,860 [35] DEBUG Logger - [InteractWith] Now attempting to highlight 949.
2016-06-24 12:39:52,871 [35] DEBUG Logger - [InteractWith] Now attempting to interact with 949.
2016-06-24 12:39:52,880 [35] INFO Logger - [LatencyWait] Now sleeping 45 ms.
2016-06-24 12:39:52,950 [35] DEBUG Logger - [WaitForAreaChange]
2016-06-24 12:39:52,950 [35] DEBUG Logger - [WaitForAreaChange] We have been waiting 00:00:00.0000031 for an area change.
2016-06-24 12:39:52,951 [35] INFO Logger - [LatencyWait] Now sleeping 45 ms.
2016-06-24 12:39:54,304 [35] DEBUG Logger - [ExilePather] Now reloading the pathfinder.
2016-06-24 12:39:54,306 [35] INFO Logger - [1.6352 ms] Layer0 + UnblockTriggerableBlockages
2016-06-24 12:39:54,307 [35] DEBUG Logger - [ExilePather] Now creating the navmesh.
2016-06-24 12:39:54,399 [35] INFO Logger - [92.5633 ms] ProcessEntireZone
2016-06-24 12:39:54,400 [35] DEBUG Logger - [ExilePather] The navmesh has been created.
2016-06-24 12:39:54,400 [35] DEBUG Logger - [ExilePather] The pathfinder has been reloaded.
2016-06-24 12:39:54,972 [35] DEBUG Logger - [TakeClosestPortal] The portal has been taken.
2016-06-24 12:39:54,995 [35] INFO Logger - [CommonEvents] core_area_changed_event
2016-06-24 12:39:54,995 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:39:54,996 [35] DEBUG Logger - [ResurrectTask] Now resetting task state.
2016-06-24 12:39:54,996 [35] DEBUG Logger - [HandleBlockingChestsTask] Now resetting task state.
2016-06-24 12:39:54,997 [35] DEBUG Logger - [HandleDoorsTask] Now resetting task state.
2016-06-24 12:39:54,997 [35] DEBUG Logger - [SellTask] Now resetting task state.
2016-06-24 12:39:54,998 [35] DEBUG Logger - [RecipeTask] Resetting task's state
2016-06-24 12:39:54,998 [35] DEBUG Logger - [GCPRecipeTask] Now resetting task state.
2016-06-24 12:39:54,998 [35] DEBUG Logger - [WithdrawTask] Now resetting task state.
2016-06-24 12:39:55,017 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces] Flushing data...
2016-06-24 12:39:55,018 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Chest] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,018 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][LeftHand] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,018 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Boots] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,019 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Rings] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,019 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Neck] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,019 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Belt] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,020 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Head] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,020 [35] DEBUG Logger - [TemporaryPickedUpRecipePieces][Gloves] Old Value : 0 | New Value (should be zero) : 0
2016-06-24 12:39:55,021 [35] DEBUG Logger - [GemLeveler] Now resetting task state.
2016-06-24 12:39:55,411 [35] DEBUG Logger - [OpenInventoryPanel]
2016-06-24 12:39:55,412 [35] DEBUG Logger - [OpenInventoryPanel] The InventoryUi is not opened. Now opening it.
2016-06-24 12:39:55,419 [35] INFO Logger - [LatencyWait] Now sleeping 60 ms.
2016-06-24 12:39:55,498 [35] INFO Logger - [ReactionWait] Now sleeping 269 ms.
2016-06-24 12:39:55,774 [35] DEBUG Logger - [IdentifyItemsTask] Nothing to id, KEEP ON GOING BITCHES
2016-06-24 12:39:55,780 [35] DEBUG Logger - [CustomRules][Keep] item.IsCurrency returned true for Scroll of Wisdom
2016-06-24 12:39:55,786 [35] DEBUG Logger - [CustomRules][Keep] item.IsCurrency returned true for Portal Scroll
2016-06-24 12:39:55,788 [35] DEBUG Logger - [CountReagentsTask] Executing...
2016-06-24 12:39:55,789 [35] DEBUG Logger - [CloseBlockingWindows] LokiPoe.Gui.IsLeftPanelShown || LokiPoe.Gui.IsRightPanelShown. Closing them.
2016-06-24 12:39:55,816 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:39:55,828 [35] INFO Logger - [BeginUseAt] 8 False {134, 184}
2016-06-24 12:39:55,937 [35] DEBUG Logger - [MoveToLocation] Now moving towards {128, 167}. We have been performing this task for 00:00:00.1206941.
2016-06-24 12:39:56,041 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:39:56,042 [35] DEBUG Logger - [FinishCurrentAction] Waited 0 ms for the action to finish Move.
2016-06-24 12:39:56,148 [35] DEBUG Logger - [FinishCurrentAction] Waited 105 ms for the action to finish Move.
2016-06-24 12:39:56,252 [35] DEBUG Logger - [FinishCurrentAction] Waited 209 ms for the action to finish Move.
2016-06-24 12:39:56,271 [35] DEBUG Logger - [InteractWith] Now attempting to highlight 13.
2016-06-24 12:39:56,278 [35] DEBUG Logger - [InteractWith] Now attempting to interact with 13.
2016-06-24 12:39:56,284 [35] INFO Logger - [LatencyWait] Now sleeping 45 ms.
2016-06-24 12:39:56,346 [35] DEBUG Logger - [FinishCurrentAction] Waited 0 ms for the action to finish Interaction.
2016-06-24 12:39:56,460 [35] DEBUG Logger - [FinishCurrentAction] Waited 114 ms for the action to finish Interaction.
2016-06-24 12:39:56,534 [35] DEBUG Logger - [WaitForStashPanel]
2016-06-24 12:39:56,939 [35] INFO Logger - [LatencyWait] Now sleeping 19 ms.
2016-06-24 12:39:56,959 [35] DEBUG Logger - [WaitForStashPanel]
2016-06-24 12:39:56,961 [35] INFO Logger - [LatencyWait] Now sleeping 31 ms.
2016-06-24 12:40:04,975 [35] INFO Logger - [WithdrawTask] We do not need to withdraw any currency items. Skipping this task until an area change or restart.
2016-06-24 12:40:04,978 [35] INFO Logger - [SellMapTask] No map group for sale was found.
2016-06-24 12:40:04,979 [35] DEBUG Logger - [FindProperMap] Total maps (non unique) amount: 29
2016-06-24 12:40:04,980 [35] DEBUG Logger - [FindMap][Advanced] Map amount to check: 21
2016-06-24 12:40:04,993 [35] INFO Logger - [ClickLMB] sleepTime = 114.
2016-06-24 12:40:05,136 [35] INFO Logger - [ClickLMB] sleepTime = 221.
2016-06-24 12:40:05,368 [35] INFO Logger - [LatencyWait] Now sleeping 23 ms.
2016-06-24 12:40:05,408 [35] INFO Logger - [TakeMapTask] Map of choice is "Jungle Valley Map" (7).
2016-06-24 12:40:05,424 [35] INFO Logger - [ClickLMB] sleepTime = 181.
2016-06-24 12:40:05,619 [35] INFO Logger - [LatencyWait] Now sleeping 46 ms.
2016-06-24 12:40:05,675 [35] INFO Logger - [ReactionWait] Now sleeping 268 ms.
2016-06-24 12:40:05,968 [35] INFO Logger - [ReactionWait] Now sleeping 275 ms.
2016-06-24 12:40:06,262 [35] DEBUG Logger - [CachedItem] returned null on Wrapper.Inventory.GetIte(...)
2016-06-24 12:40:06,263 [35] DEBUG Logger - [CachedItem] FullName was Jungle Valley Map, name was Jungle Valley Map
2016-06-24 12:40:06,263 [35] DEBUG Logger - [CachedItem] Wrapper was Loki.Game.InventoryControlWrapper
2016-06-24 12:40:06,264 [35] DEBUG Logger - [CommunityLib][StashCache] An item is being removed from cache
2016-06-24 12:40:06,264 [35] DEBUG Logger - [TakeMapTask] Settings for "Jungle Valley Map" = Enabled: True Name: Jungle Valley Map Tier: 7 LayoutType: A Priority: 23 Keep: True UpgradeTo: Magic RerollMethod: ScourTransmute MagicToRare: False ChiselAmount: 20 RunUnidentified: False Vaal: False SacPieces: 0 IgnoreBossRoom: True MinQuantity: 0 MinRarity: 0 MinPackSize: 0 MobsToFinish: 0 PercentageToCheck: 0 TrackMobTaskRange: 0
2016-06-24 12:40:06,265 [35] DEBUG Logger - [TakeMapTask] Map rarity is "Normal".
2016-06-24 12:40:06,265 [35] DEBUG Logger - [TakeMapTask] Handling normal map.
2016-06-24 12:40:06,266 [35] DEBUG Logger - [UseOrbOnItem] Using "Cartographer's Chisel" on "Jungle Valley Map".
2016-06-24 12:40:06,305 [35] INFO Logger - [ClickLMB] sleepTime = 222.
2016-06-24 12:40:06,543 [35] INFO Logger - [LatencyWait] Now sleeping 26 ms.
2016-06-24 12:40:06,736 [35] DEBUG Logger - [CommunityLib] Failed to use item on item. Unsupported
2016-06-24 12:40:06,761 [35] INFO Logger - [LatencyWait] Now sleeping 46 ms.
2016-06-24 12:40:07,140 [35] DEBUG Logger - [UseOrbOnItem] Using "Cartographer's Chisel" on "Jungle Valley Map".
2016-06-24 12:40:07,296 [35] DEBUG Logger - [CommunityLib] Failed to use item on item. Unsupported
2016-06-24 12:40:07,322 [35] INFO Logger - [LatencyWait] Now sleeping 46 ms.
2016-06-24 12:40:07,702 [35] DEBUG Logger - [UseOrbOnItem] Using "Cartographer's Chisel" on "Jungle Valley Map".
2016-06-24 12:40:07,860 [35] DEBUG Logger - [CommunityLib] Failed to use item on item. Unsupported
2016-06-24 12:40:07,886 [35] INFO Logger - [LatencyWait] Now sleeping 48 ms.
2016-06-24 12:40:08,259 [35] DEBUG Logger - [UseOrbOnItem] Using "Cartographer's Chisel" on "Jungle Valley Map".
2016-06-24 12:40:08,417 [35] DEBUG Logger - [CommunityLib] Failed to use item on item. Unsupported
2016-06-24 12:40:08,440 [35] INFO Logger - [LatencyWait] Now sleeping 48 ms.
2016-06-24 12:40:08,835 [35] DEBUG Logger - [UseOrbOnItem] Using "Orb of Transmutation" on "Jungle Valley Map".
2016-06-24 12:40:08,895 [35] DEBUG Logger - [CommunityLib] Failed to use item on item. Unsupported
2016-06-24 12:40:08,919 [35] INFO Logger - [LatencyWait] Now sleeping 48 ms.
2016-06-24 12:40:09,266 [35] DEBUG Logger - [TakeMapTask] Handling upgraded map.
2016-06-24 12:40:09,267 [35] DEBUG Logger - [HandleUpgradedMap] I can/have to run the map without upgrading it.
2016-06-24 12:40:09,268 [35] DEBUG Logger - [TakeMapTask] "Ceremonial Jungle Valley Map of Bloodlines" explicit affixes count = 2.
2016-06-24 12:40:09,269 [35] WARN Logger - [TakeMapTask] Now going to "Ceremonial Jungle Valley Map of Bloodlines".
2016-06-24 12:40:09,269 [35] DEBUG Logger - [CloseBlockingWindows] LokiPoe.Gui.IsLeftPanelShown || LokiPoe.Gui.IsRightPanelShown. Closing them.
2016-06-24 12:40:09,302 [35] DEBUG Logger - [MoveAtOnce] Moving to Map Device at {138, 202} (distance: 30)
2016-06-24 12:40:09,313 [35] INFO Logger - [BeginUseAt] 8 False {140, 184}
2016-06-24 12:40:09,432 [35] DEBUG Logger - [MoveAtOnce] Moving to Map Device at {138, 202} (distance: 27)
2016-06-24 12:40:09,556 [35] DEBUG Logger - [MoveAtOnce] Moving to Map Device at {138, 202} (distance: 22)
2016-06-24 12:40:09,566 [35] INFO Logger - [UseAt] 8 False {140, 195}
2016-06-24 12:40:09,678 [35] DEBUG Logger - [MoveAtOnce] Moving to Map Device at {138, 202} (distance: 17)
2016-06-24 12:40:09,689 [35] INFO Logger - [UseAt] 8 False {141, 200}
2016-06-24 12:40:09,695 [35] INFO Logger - [InjectKeyEvent] 126 ms sleep for 84.
2016-06-24 12:40:09,946 [35] DEBUG Logger - [FinishCurrentAction] Waited 0 ms for the action to finish Move.
2016-06-24 12:40:10,062 [35] DEBUG Logger - [FinishCurrentAction] Waited 116 ms for the action to finish Move.
2016-06-24 12:40:10,177 [35] DEBUG Logger - [FinishCurrentAction] Waited 231 ms for the action to finish Move.
2016-06-24 12:40:10,198 [35] INFO Logger - [OpenMapDevice] Attempt: 1/5
2016-06-24 12:40:10,199 [35] DEBUG Logger - [InteractWith] Now attempting to highlight 15.
2016-06-24 12:40:10,206 [35] DEBUG Logger - [InteractWith] Now attempting to interact with 15.
2016-06-24 12:40:10,211 [35] INFO Logger - [LatencyWait] Now sleeping 48 ms.
2016-06-24 12:40:10,271 [35] DEBUG Logger - [TryInteract] "Map Device" has been successfully interacted.
2016-06-24 12:40:10,272 [35] DEBUG Logger - [OpenMapDevice] Map device has been successfully opened.
2016-06-24 12:40:10,273 [35] INFO Logger - [PlaceMapIntoDevice] Attempt: 1/5
2016-06-24 12:40:10,273 [35] DEBUG Logger - [FastMoveFromInventory] Fast moving "Ceremonial Jungle Valley Map of Bloodlines" from {0, 0}.
2016-06-24 12:40:10,285 [35] INFO Logger - [ClickLMB] sleepTime = 169.
2016-06-24 12:40:10,573 [35] DEBUG Logger - [WaitFor] Waiting for fast move (0.1/5)
2016-06-24 12:40:10,574 [35] INFO Logger - [ReactionWait] Now sleeping 257 ms.
2016-06-24 12:40:10,837 [35] DEBUG Logger - [FastMoveFromInventory] "Ceremonial Jungle Valley Map of Bloodlines" has been successfully fast moved from {0, 0}.
2016-06-24 12:40:10,838 [35] DEBUG Logger - [PlaceMapIntoDevice] Item has been successfully placed into Map Device.
2016-06-24 12:40:10,838 [35] DEBUG Logger - [ActivateMapDevice] Now activating the Map Device.
2016-06-24 12:40:10,839 [35] INFO Logger - [ActivateMapDevice] Attempt: 1/3
2016-06-24 12:40:10,863 [35] DEBUG Logger - [ActivateMapDevice] Map Device has been successfully activated.
2016-06-24 12:40:11,002 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.11/15)
2016-06-24 12:40:11,186 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.3/15)
2016-06-24 12:40:11,368 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.48/15)
2016-06-24 12:40:11,524 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.64/15)
2016-06-24 12:40:11,679 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.79/15)
2016-06-24 12:40:11,809 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (0.92/15)
2016-06-24 12:40:11,971 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.08/15)
2016-06-24 12:40:12,156 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.27/15)
2016-06-24 12:40:12,286 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.4/15)
2016-06-24 12:40:12,428 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.54/15)
2016-06-24 12:40:12,604 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.72/15)
2016-06-24 12:40:12,770 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (1.88/15)
2016-06-24 12:40:12,913 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.02/15)
2016-06-24 12:40:13,090 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.2/15)
2016-06-24 12:40:13,235 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.35/15)
2016-06-24 12:40:13,415 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.53/15)
2016-06-24 12:40:13,584 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.7/15)
2016-06-24 12:40:13,747 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (2.86/15)
2016-06-24 12:40:13,902 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.01/15)
2016-06-24 12:40:14,029 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.14/15)
2016-06-24 12:40:14,179 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.29/15)
2016-06-24 12:40:14,306 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.42/15)
2016-06-24 12:40:14,448 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.56/15)
2016-06-24 12:40:14,586 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.7/15)
2016-06-24 12:40:14,737 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.85/15)
2016-06-24 12:40:14,881 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (3.99/15)
2016-06-24 12:40:15,025 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.14/15)
2016-06-24 12:40:15,210 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.32/15)
2016-06-24 12:40:15,362 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.47/15)
2016-06-24 12:40:15,491 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.6/15)
2016-06-24 12:40:15,654 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.77/15)
2016-06-24 12:40:15,817 [35] DEBUG Logger - [WaitFor] Waiting for new map portals spawning (4.93/15)
2016-06-24 12:40:16,337 [35] DEBUG Logger - [InteractWith] Now attempting to highlight 17.
2016-06-24 12:40:16,344 [35] DEBUG Logger - [InteractWith] Now attempting to interact with 17.
2016-06-24 12:40:16,350 [35] INFO Logger - [LatencyWait] Now sleeping 24 ms.
2016-06-24 12:40:16,388 [35] DEBUG Logger - [FinishCurrentAction] Waited 0 ms for the action to finish Interaction.
2016-06-24 12:40:16,493 [35] DEBUG Logger - [FinishCurrentAction] Waited 105 ms for the action to finish Interaction.
2016-06-24 12:40:16,538 [35] DEBUG Logger - [TryInteract] "Jungle Valley" has been successfully interacted.
2016-06-24 12:40:16,539 [35] DEBUG Logger - [WaitForAreaChange]
2016-06-24 12:40:16,539 [35] DEBUG Logger - [WaitForAreaChange] We have been waiting 00:00:00.0000034 for an area change.
2016-06-24 12:40:16,539 [35] INFO Logger - [LatencyWait] Now sleeping 24 ms.
2016-06-24 12:40:17,620 [35] DEBUG Logger - [WaitForAreaChange] We have been waiting 00:00:01.0805757 for an area change.
2016-06-24 12:40:17,621 [35] INFO Logger - [LatencyWait] Now sleeping 24 ms.
2016-06-24 12:40:19,391 [35] DEBUG Logger - [ExilePather] Now reloading the pathfinder.
2016-06-24 12:40:19,393 [35] INFO Logger - [1.4671 ms] Layer0 + UnblockTriggerableBlockages
2016-06-24 12:40:19,393 [35] DEBUG Logger - [ExilePather] Now creating the navmesh.
2016-06-24 12:40:20,466 [35] INFO Logger - [1072.1243 ms] ProcessEntireZone
2016-06-24 12:40:20,466 [35] DEBUG Logger - [ExilePather] The navmesh has been created.
2016-06-24 12:40:20,466 [35] DEBUG Logger - [ExilePather] The pathfinder has been reloaded.
2016-06-24 12:40:20,485 [35] INFO Logger - [GridExplorer] AutoResetOnAreaChange is being set to True.
2016-06-24 12:40:20,485 [35] INFO Logger - [GridExplorer] TileKnownRadius is being set to 7.
2016-06-24 12:40:20,485 [35] INFO Logger - [GridExplorer] TileSeenRadius is being set to 5.
2016-06-24 12:40:20,485 [35] INFO Logger - [GridExplorer] AutoResetOnAreaChange is being set to False.
2016-06-24 12:40:20,486 [35] DEBUG Logger - [GridExplorer] Now segmenting the current area.
2016-06-24 12:40:20,816 [35] DEBUG Logger - [GridExplorer] Area segmentation complete 00:00:00.3297650.
2016-06-24 12:40:20,843 [35] INFO Logger - [CommonEvents] core_area_changed_event
2016-06-24 12:40:20,843 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:40:20,844 [35] DEBUG Logger - [ResurrectTask] Now resetting task state.
2016-06-24 12:40:20,844 [35] DEBUG Logger - [HandleBlockingChestsTask] Now resetting task state.
2016-06-24 12:40:20,845 [35] DEBUG Logger - [HandleDoorsTask] Now resetting task state.
2016-06-24 12:40:20,845 [35] DEBUG Logger - [SellTask] Now resetting task state.
2016-06-24 12:40:20,845 [35] DEBUG Logger - [RecipeTask] Resetting task's state
2016-06-24 12:40:20,846 [35] DEBUG Logger - [GCPRecipeTask] Now resetting task state.
2016-06-24 12:40:20,846 [35] DEBUG Logger - [WithdrawTask] Now resetting task state.
2016-06-24 12:40:20,866 [35] DEBUG Logger - [GemLeveler] Now resetting task state.
2016-06-24 12:40:20,866 [35] WARN Logger - [CurrentMap] This map has boss room.
2016-06-24 12:40:20,866 [35] WARN Logger - [CurrentMap] Boss room in this map will be ignored according to settings.
2016-06-24 12:40:20,867 [35] INFO Logger - [MapBuddy] New map has been entered: Jungle Valley Map (7)
2016-06-24 12:40:20,867 [35] DEBUG Logger - [MapBuddy][Statistics] Current map Name: Jungle Valley Map, Tier: 7
2016-06-24 12:40:20,868 [35] INFO Logger - [GridExplorer] TileKnownRadius is being set to 5.
2016-06-24 12:40:20,868 [35] INFO Logger - [GridExplorer] TileSeenRadius is being set to 4.
2016-06-24 12:40:20,868 [35] INFO Logger - [MapSpecificTweaks] Setting tile seen radius to 3.
2016-06-24 12:40:20,869 [35] INFO Logger - [GridExplorer] TileSeenRadius is being set to 3.
2016-06-24 12:40:20,869 [35] DEBUG Logger - [TrackMobTask] New map entered.
2016-06-24 12:40:20,869 [35] DEBUG Logger - [TrackMobTask] Monster cache has been cleared.
2016-06-24 12:40:20,870 [35] INFO Logger - [AreaTransitionTask] Transition cache has been cleared.
2016-06-24 12:40:20,912 [35] INFO Logger - [EnableAlwaysHiglight] Now enabling Always Highlight.
2016-06-24 12:40:20,976 [35] INFO Logger - [PostCombatHookTask] GemLevelerPro returned true.
2016-06-24 12:40:21,078 [35] INFO Logger - [WithdrawTask] We cannot withdraw items out of town/hideout. Skipping this task until an area change or restart.
2016-06-24 12:40:21,085 [35] WARN Logger - [MapExplorationTask] Explored percent: 7%. Monsters Remaining: 51. Percentage to Check: 0. Mobs to Finish: 0. Boss room will be ignored. Exploration task is not needed anymore.
2016-06-24 12:40:21,190 [35] WARN Logger - [MapExplorationCompleteTask] Now finishing the map run.
2016-06-24 12:40:21,191 [1] INFO Logger - [MapBuddy][Statistics] Map finished. 6/24/2016 10:40:21 PM. Name:Jungle Valley Map. Time: 00:00:00 (00:00:00). Mods:Ceremonial, of Bloodlines. Deaths: 0
2016-06-24 12:40:21,192 [35] DEBUG Logger - [CreatePortalToTown] Now opening the inventory panel.
2016-06-24 12:40:21,192 [35] DEBUG Logger - [OpenInventoryPanel]
2016-06-24 12:40:21,194 [35] DEBUG Logger - [OpenInventoryPanel] The InventoryUi is not opened. Now opening it.
2016-06-24 12:40:21,201 [35] INFO Logger - [LatencyWait] Now sleeping 46 ms.
2016-06-24 12:40:21,255 [35] INFO Logger - [ReactionWait] Now sleeping 296 ms.
2016-06-24 12:40:21,338 [35] DEBUG Logger - [ResetAnchorPoint] Setting AnchorPoint to {234, 407} for 1379511060.
2016-06-24 12:40:21,338 [35] DEBUG Logger - [ResetAnchorPoint] Setting CurrentAnchorPoint to {234, 407} for 1379511060.
2016-06-24 12:40:21,553 [35] INFO Logger - [ReactionWait] Now sleeping 277 ms.
2016-06-24 12:40:21,834 [35] DEBUG Logger - [CreatePortalToTown] Now searching the main inventory for a Portal Scroll.
2016-06-24 12:40:21,834 [35] DEBUG Logger - [CreatePortalToTown] Now using the Portal Scroll.
2016-06-24 12:40:21,860 [35] INFO Logger - [LatencyWait] Now sleeping 34 ms.
2016-06-24 12:40:21,899 [35] INFO Logger - [ReactionWait] Now sleeping 282 ms.
2016-06-24 12:40:22,201 [35] DEBUG Logger - [CloseBlockingWindows] LokiPoe.Gui.IsLeftPanelShown || LokiPoe.Gui.IsRightPanelShown. Closing them.
2016-06-24 12:40:22,228 [35] INFO Logger - [TakeClosestPortal] Now disabling Always Highlight to avoid label issues.
2016-06-24 12:40:22,253 [35] DEBUG Logger - [TakeClosestPortal] Now waiting for the portal to spawn. 00:00:00.0249042 elapsed.
2016-06-24 12:40:22,254 [35] INFO Logger - [LatencyWait] Now sleeping 34 ms.
2016-06-24 12:40:22,290 [35] DEBUG Logger - <log4net.Error>Exception during StringFormat: Index (zero based) must be greater than or equal to zero and less than the size of the argument list. <format>[TakeClosestPortal] The portal was found at {234, 408}.</format><args>{}</args></log4net.Error>
2016-06-24 12:40:22,291 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:40:22,291 [35] DEBUG Logger - <log4net.Error>Exception during StringFormat: Index (zero based) must be greater than or equal to zero and less than the size of the argument list. <format>[TakeClosestPortal] The portal to interact with is 904 at {234, 408}.</format><args>{}</args></log4net.Error>
2016-06-24 12:40:22,292 [35] DEBUG Logger - [InteractWith] Now attempting to highlight 904.
2016-06-24 12:40:22,298 [35] DEBUG Logger - [InteractWith] Now attempting to interact with 904.
2016-06-24 12:40:22,304 [35] INFO Logger - [LatencyWait] Now sleeping 34 ms.
2016-06-24 12:40:22,340 [35] DEBUG Logger - [WaitForAreaChange]
2016-06-24 12:40:22,341 [35] DEBUG Logger - [WaitForAreaChange] We have been waiting 00:00:00.0000040 for an area change.
2016-06-24 12:40:22,341 [35] INFO Logger - [LatencyWait] Now sleeping 34 ms.
2016-06-24 12:40:23,419 [35] DEBUG Logger - [WaitForAreaChange] We have been waiting 00:00:01.0787353 for an area change.
2016-06-24 12:40:23,420 [35] INFO Logger - [LatencyWait] Now sleeping 34 ms.
2016-06-24 12:40:24,579 [35] DEBUG Logger - [ExilePather] Now reloading the pathfinder.
2016-06-24 12:40:24,580 [35] INFO Logger - [1.2576 ms] Layer0 + UnblockTriggerableBlockages
2016-06-24 12:40:24,581 [35] DEBUG Logger - [ExilePather] Now creating the navmesh.
2016-06-24 12:40:24,719 [35] INFO Logger - [137.955 ms] ProcessEntireZone
2016-06-24 12:40:24,720 [35] DEBUG Logger - [ExilePather] The navmesh has been created.
2016-06-24 12:40:24,720 [35] DEBUG Logger - [ExilePather] The pathfinder has been reloaded.
2016-06-24 12:40:24,740 [35] DEBUG Logger - [TakeClosestPortal] The portal has been taken.
2016-06-24 12:40:24,762 [35] INFO Logger - [CommonEvents] core_area_changed_event
2016-06-24 12:40:24,763 [35] INFO Logger - [DefaultPlayerMover::Execute] SetDoAdjustments => False
2016-06-24 12:40:24,763 [35] DEBUG Logger - [ResurrectTask] Now resetting task state.
2016-06-24 12:40:24,763 [35] DEBUG Logger - [HandleBlockingChestsTask] Now resetting task state.
2016-06-24 12:40:24,764 [35] DEBUG Logger - [HandleDoorsTask] Now resetting task state.
2016-06-24 12:40:24,764 [35] DEBUG Logger - [SellTask] Now resetting task state.
2016-06-24 12:40:24,765 [35] DEBUG Logger - [RecipeTask] Resetting task's state
2016-06-24 12:40:24,765 [35] DEBUG Logger - [GCPRecipeTask] Now resetting task state.