search

zhyrohaad / AlasiyaEvE

Public repo for bug tracking the private Alasiya-EvE codebase
2 stars 0 forks source link

Firing Missiles causes multiple client exceptions #87

Open ryllae opened 4 years ago

ryllae commented 4 years ago

Lock a target, fire some missiles. I see two exceptions on every missile fired. The first appears to be something about updating the ammo quantity?


46325   2020.02.19 17:59:24:876 UnlockItem_NoLock 140011940
46326   2020.02.19 17:59:24:876 UnlockItem_NoLock irrelevant item: 140011940
46327   2020.02.19 17:59:24:876 OnItemChange <DBRow object [140011940L, 206, 90000005, 140009786, 29, 0, 385, 8, '', 0, 0]> {5: 1}
46328   2020.02.19 17:59:24:876 UpdateItem: <DBRow object [140011940L, 206, 90000005, 140009786, 29, 0, 385, 8, '', 0, 0]> None 0
46329   2020.02.19 17:59:24:877 Write:  Packet::CallReq (Address::Client(clientID="0",callID="231",service="None"),Address::Node(nodeID="888444",service="None",callID="None"),94 bytes,[(1, <MarshalStream '~\x00\x00\x00\x00\x14\x04\x13\x0cN=888444:174\x13\x0bItemGetInfo%/\x04\xa4iX\x0-
46330   2020.02.19 17:59:24:877 8\x16\x01\t\x11\x87'>)],{}, None)
46331   2020.02.19 17:59:24:877 STACKTRACE #463 logged at 02/19/2020 11:59:25
46332   2020.02.19 17:59:24:877 infoSvc processing ixQuantity change
46333   2020.02.19 17:59:24:877 /common/lib/bluepy.py(86) CallWrapper
46334   2020.02.19 17:59:24:877 /common/script/sys/servicemanager.py(667) MollycoddledUthread
46335   2020.02.19 17:59:24:877 /client/script/ui/services/infosvc.py(87) OnItemChange
46336   2020.02.19 17:59:24:877 item = <DBRow object [140011940L, 206, 90000005, 140009786, 29, 0, 385, 8, '', 0, 0]>
46337   2020.02.19 17:59:24:877 self = <svc.Info instance at 0x10A27A58>
46338   2020.02.19 17:59:24:877 itemGone = False
46339   2020.02.19 17:59:24:877 change = {5: 1}
46343   2020.02.19 17:59:24:877 STACKTRACE END

A moment later I see a stack overflow, but haven't grokked the source yet:


46344   2020.02.19 17:59:24:877 Read:  Packet::Notification (Address::Node(nodeID="888444",service="None",callID="None"),Address::BroadCast(broadcastID="OnItemChange",narrowcast="[]",idtype="clientID"),221 bytes,[(0, <MarshalStream '~\x00\x00\x00\x00,\x08,\t,*"\x14\x03\x02\x14blue.DB-
46345   2020.02.19 17:59:24:877 RowDescriptor%\x14\t,\x11\'\x06\x14,\x11J\x06\x03,\x11?\x06\x03,\x11,\x06\x03,\x13\x06flagID\x06\x02,\x11A\x06\x03,\x11#\x06\x02,\x11\n\x06\x03,\x11\x17\x05\x81\x00\x15\x02,\x13\tstacksize\x02\x0eutil.StackSize,\x11G\x02\x0eutil.Singleton--\x11\xb4-
46346   2020.02.19 17:59:24:877 \xa4iX\x08\xa7\xce\xf4\x85J]\x05\xc7\x08\x86\x81\x01\x0e\x16\x02\x06\x1d\x06\x04\x04:aX\x08\x06\x03'>)],{'sn': 294}, None)
46347   2020.02.19 17:59:24:877 BroadcastStuff::Notify( OnItemChange ,...)
46348   2020.02.19 17:59:24:878 UnlockItem_NoLock 140011417
46349   2020.02.19 17:59:24:878 UnlockItem_NoLock irrelevant item: 140011417
46350   2020.02.19 17:59:24:878 OnItemChange <DBRow object [140011417L, 206, 90000005, 140009786, 5, 45, 385, 8, '', 45, 0]> {4: 29}
46351   2020.02.19 17:59:24:878 None _UnloadItem::Deleting dogmaItem 140011417
46352   2020.02.19 17:59:24:878 OnItemChange {4: 29} <DBRow object [140011417L, 206, 90000005, 140009786, 5, 45, 385, 8, '', 45, 0]>
46353   2020.02.19 17:59:24:878 OnItemChange (fancy) {'flagID': (29, '->', 5)} <DBRow object [140011417L, 206, 90000005, 140009786, 5, 45, 385, 8, '', 45, 0]>
46354   2020.02.19 17:59:24:878 AddItem None 45 Nova Heavy Missile
46355   2020.02.19 17:59:24:878 SetItem 0 [45 Nova Heavy Missile]
46356   2020.02.19 17:59:24:878 GetIcon( 2 ) returns InvItem
46357   2020.02.19 17:59:24:880 EXCEPTION #464 logged at  02/19/2020 11:59:25 Exception in Error Logging, preventing infinite recursion
46358   2020.02.19 17:59:24:880 Caught at:
46359   2020.02.19 17:59:24:880 /common/lib/bluepy.py(86) CallWrapper
46360   2020.02.19 17:59:24:880 /common/script/sys/alert.py(415) SendStackTraceAlert_thread
46361   2020.02.19 17:59:24:880 Thrown at:
46362   2020.02.19 17:59:24:880 /common/script/sys/alert.py(413) SendStackTraceAlert_thread
46363   2020.02.19 17:59:24:880 /common/script/sys/alert.py(449) __SendStackTraceAlert
46364   2020.02.19 17:59:24:880 locationID1 = None
46365   2020.02.19 17:59:24:880 origin = 3
46366   2020.02.19 17:59:24:880 locationID2 = None
46367   2020.02.19 17:59:24:880 stackTrace = "STACKTRACE #462 logged at 02/19/2020 11:59:25\ninvCache processing a ixQuantity change\n/common/lib/bluepy.py(86) CallWrapper\n/common/script/sys/servicemanager.py(667) MollycoddledUthread\n/client/script/environment/invcache.py(1-
46368   2020.02.19 17:59:24:880 84) OnItemChange\n        item = <DBRow object [140011940L, 206, 90000005, 140009786, 29, 0, 385, 8, '', 0, 0]>\n        self = <svc.invCache instance at 0x07BAB288>\n        change = {5: 1}\nThread Locals:  session was <Session: (sid:2882359874356-
46369   2020.02.19 17:59:24:880 165868, clientID:0, mutating:0, locationid:30001392, corprole:0xffffe07ffffff81L, userid:576, languageID:EN, role:0x63f8000280c41000L, charid:90000005, address:47.218.232.252:50206, userType:30, sessionType:5, regionid:10000016, constellationid:200-
46370   2020.02.19 17:59:24:880 00204, allianceid:0, warfactionid:0, corpid:98000002, shipid:140009786, solarsystemid:30001392, solarsystemid2:30001392, hqID:60004450, baseID:60004450, rolesAtAll:0xffffe07ffffff81L, rolesAtHQ:0xffffe07ffffff81L, rolesAtBase:0xffffe07ffffff81L, ro-
46371   2020.02.19 17:59:24:880 lesAtOther:0xffffe07ffffff81L, genderID:0, bloodl...
46372   2020.02.19 17:59:24:880 i = 0
46373   2020.02.19 17:59:24:880 GetArgumentsFromStackTrace = <function GetArgumentsFromStackTrace at 0x36DE9630>
46374   2020.02.19 17:59:24:880 self = <svc.Alert instance at 0x43E62508>
46375   2020.02.19 17:59:24:880 userID = None
46376   2020.02.19 17:59:24:880 nodeID = None
46377   2020.02.19 17:59:24:880 nextErrorKeyHash = None
46378   2020.02.19 17:59:24:880 mode = 'Error'
46379   2020.02.19 17:59:24:880 charID = None
46380   2020.02.19 17:59:24:880 stackID = (-1818675034, '/common/lib/bluepy.py(86) CallWrapper\n/common/script/sys/servicemanager.py(667) MollycoddledUthread\n/client/script/environment/invcache.py(184) OnItemChange\n')
46381   2020.02.19 17:59:24:880 TypeError: 'NoneType' object is not iterable
46382   2020.02.19 17:59:24:880 Thread Locals:  session was <Session: (sid:2882359874356165868, clientID:0, mutating:0, locationid:30001392, corprole:0xffffe07ffffff81L, userid:576, languageID:EN, role:0x63f8000280c41000L, charid:90000005, address:47.218.232.252:50206, userType:30, s-
46383   2020.02.19 17:59:24:880 essionType:5, regionid:10000016, constellationid:20000204, allianceid:0, warfactionid:0, corpid:98000002, shipid:140009786, solarsystemid:30001392, solarsystemid2:30001392, hqID:60004450, baseID:60004450, rolesAtAll:0xffffe07ffffff81L, rolesAtHQ:0x-
46384   2020.02.19 17:59:24:880 ffffe07ffffff81L, rolesAtBase:0xffffe07ffffff81L, rolesAtOther:0xffffe07ffffff81L, genderID:0, bloodlineID:1, raceID:1, corpAccountKey:1000)>
46385   2020.02.19 17:59:24:880 EXCEPTION END
46386   2020.02.19 17:59:24:881 EXCEPTION #465 logged at  02/19/2020 11:59:25 Exception in Error Logging, preventing infinite recursion
46387   2020.02.19 17:59:24:881 Caught at:
46388   2020.02.19 17:59:24:881 /common/lib/bluepy.py(86) CallWrapper
46389   2020.02.19 17:59:24:881 /common/script/sys/alert.py(415) SendStackTraceAlert_thread
46390   2020.02.19 17:59:24:881 Thrown at:
46391   2020.02.19 17:59:24:881 /common/script/sys/alert.py(413) SendStackTraceAlert_thread
46392   2020.02.19 17:59:24:881 /common/script/sys/alert.py(449) __SendStackTraceAlert
46393   2020.02.19 17:59:24:881 locationID1 = None
46394   2020.02.19 17:59:24:881 origin = 3
46395   2020.02.19 17:59:24:881 locationID2 = None
46396   2020.02.19 17:59:24:881 stackTrace = "STACKTRACE #463 logged at 02/19/2020 11:59:25\ninfoSvc processing ixQuantity change\n/common/lib/bluepy.py(86) CallWrapper\n/common/script/sys/servicemanager.py(667) MollycoddledUthread\n/client/script/ui/services/infosvc.py(87) O-
46397   2020.02.19 17:59:24:881 nItemChange\n        item = <DBRow object [140011940L, 206, 90000005, 140009786, 29, 0, 385, 8, '', 0, 0]>\n        self = <svc.Info instance at 0x10A27A58>\n        itemGone = False\n        change = {5: 1}\nThread Locals:  session was <Session: (-
46398   2020.02.19 17:59:24:881 sid:2882359874356165868, clientID:0, mutating:0, locationid:30001392, corprole:0xffffe07ffffff81L, userid:576, languageID:EN, role:0x63f8000280c41000L, charid:90000005, address:47.218.232.252:50206, userType:30, sessionType:5, regionid:10000016, co-
46399   2020.02.19 17:59:24:881 nstellationid:20000204, allianceid:0, warfactionid:0, corpid:98000002, shipid:140009786, solarsystemid:30001392, solarsystemid2:30001392, hqID:60004450, baseID:60004450, rolesAtAll:0xffffe07ffffff81L, rolesAtHQ:0xffffe07ffffff81L, rolesAtBase:0xfff-
46400   2020.02.19 17:59:24:881 fe07ffffff81L, rolesAtOther:0xffffe07ffffff81L, g...
46401   2020.02.19 17:59:24:881 i = 0
46402   2020.02.19 17:59:24:881 GetArgumentsFromStackTrace = <function GetArgumentsFromStackTrace at 0x36DE9330>
46403   2020.02.19 17:59:24:881 self = <svc.Alert instance at 0x43E62508>
46404   2020.02.19 17:59:24:881 userID = None
46405   2020.02.19 17:59:24:881 nodeID = None
46406   2020.02.19 17:59:24:881 nextErrorKeyHash = None
46407   2020.02.19 17:59:24:881 mode = 'Error'
46408   2020.02.19 17:59:24:881 charID = None
46409   2020.02.19 17:59:24:881 stackID = (467220459, '/common/lib/bluepy.py(86) CallWrapper\n/common/script/sys/servicemanager.py(667) MollycoddledUthread\n/client/script/ui/services/infosvc.py(87) OnItemChange\n')
46410   2020.02.19 17:59:24:881 TypeError: 'NoneType' object is not iterable
46411   2020.02.19 17:59:24:881 Thread Locals:  session was <Session: (sid:2882359874356165868, clientID:0, mutating:0, locationid:30001392, corprole:0xffffe07ffffff81L, userid:576, languageID:EN, role:0x63f8000280c41000L, charid:90000005, address:47.218.232.252:50206, userType:30, s-
46412   2020.02.19 17:59:24:881 essionType:5, regionid:10000016, constellationid:20000204, allianceid:0, warfactionid:0, corpid:98000002, shipid:140009786, solarsystemid:30001392, solarsystemid2:30001392, hqID:60004450, baseID:60004450, rolesAtAll:0xffffe07ffffff81L, rolesAtHQ:0x-
46413   2020.02.19 17:59:24:881 ffffe07ffffff81L, rolesAtBase:0xffffe07ffffff81L, rolesAtOther:0xffffe07ffffff81L, genderID:0, bloodlineID:1, raceID:1, corpAccountKey:1000)>
46414   2020.02.19 17:59:24:881 EXCEPTION END
ryllae commented 4 years ago

Missiles in flight to a valid target also cause issues? I'm reasonably sure the target was still alive.

46590   2020.02.19 17:59:25:567 EXCEPTION #466 logged at  02/19/2020 11:59:25 Error adding SpaceObject of type
46591   2020.02.19 17:59:25:567 Caught at:
46592   2020.02.19 17:59:25:567 /common/lib/bluepy.py(86) CallWrapper
46593   2020.02.19 17:59:25:567 /client/script/parklife/spacemgr.py(162) DoBallsAdded_
46594   2020.02.19 17:59:25:567 /client/script/parklife/spacemgr.py(91) LoadObject
46595   2020.02.19 17:59:25:567 Thrown at:
46596   2020.02.19 17:59:25:567 /client/script/parklife/spacemgr.py(88) LoadObject
46597   2020.02.19 17:59:25:567 /client/script/environment/spaceobject/missile.py(67) Prepare
46598   2020.02.19 17:59:25:567 /client/script/environment/spaceobject/missile.py(79) EstimateTimeToTarget
46599   2020.02.19 17:59:25:567 targetBall = <destiny.ClientBall object at 0x43DCB290>
46600   2020.02.19 17:59:25:567 myPos = (-63833.199, 24228.533, 352173.063)
46601   2020.02.19 17:59:25:567 self = <destiny.ClientBall object at 0x4506A3D0>
46602   2020.02.19 17:59:25:567 bp = <destiny.Ballpark object at 0x36F091D0>
46603   2020.02.19 17:59:25:567 targetPos = (-0.429, 0.163, 2.364)
46604   2020.02.19 17:59:25:567 offset = (-63832.770, 24228.371, 352170.688)
46605   2020.02.19 17:59:25:567 now = 132266087657671380L
46606   2020.02.19 17:59:25:567 ZeroDivisionError: float division by zero
46607   2020.02.19 17:59:25:567 Thread Locals:  session was <Session: (sid:2882359874356165868, clientID:0, mutating:0, locationid:30001392, corprole:0xffffe07ffffff81L, userid:576, languageID:EN, role:0x63f8000280c41000L, charid:90000005, address:47.218.232.252:50206, userType:30, s-
46608   2020.02.19 17:59:25:567 essionType:5, regionid:10000016, constellationid:20000204, allianceid:0, warfactionid:0, corpid:98000002, shipid:140009786, solarsystemid:30001392, solarsystemid2:30001392, hqID:60004450, baseID:60004450, rolesAtAll:0xffffe07ffffff81L, rolesAtHQ:0x-
46609   2020.02.19 17:59:25:567 ffffe07ffffff81L, rolesAtBase:0xffffe07ffffff81L, rolesAtOther:0xffffe07ffffff81L, genderID:0, bloodlineID:1, raceID:1, corpAccountKey:1000)>
46610   2020.02.19 17:59:25:567 EXCEPTION END
zhyrohaad commented 4 years ago

Line # 46332. This is a known "error" with charges.
I may have to change charge rsp code a bit, and if/when I do, this error will disappear.

The second is a response error. Somehow the server is sending a location change for the "depleted" missile, from the high slot to cargo, but qty is 0.

The last seems like a distance was really wrong....so time to targ was miniscule and client don't handle it very well.

Will look into and post findings.

ryllae commented 4 years ago

I can account for the second one then - I used .spawn and was targeting a rat who's position was misrepresented.

zhyrohaad commented 4 years ago

this may not show errors on dev server. dont recall testing offhand