Page MenuHomeSchine - Phabricator

Starmade | Server Controller Exceptions
Closed, RejectedPublic

Description

Server randomly totally freezes.

Log (fragment):

[2015-08-24 16:56:38] [SERVER][UNIVERSE] LOADING SECTOR... (3, 7, 27)
[2015-08-24 16:56:38] [SERVER] Planet(920)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 17; Delay 0; DelayNR 0
[2015-08-24 16:56:38] [UNIVERSE][PLANET] LOADING PLANET CORE: ENTITY_PLANETCORE_3_7_27
[2015-08-24 16:56:39] [SERVER] Planet(922)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER][READTAG] WARNING: org.schema.game.server.controller.GameServerController@46ce3fd4 READING tag: ENTITY_PLANET_3_7_27_2_1440390867637.ent;  took long: 14ms
[2015-08-24 16:56:39] [SERVER] Planet(923)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(924)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(925)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER][READTAG] WARNING: org.schema.game.server.controller.GameServerController@46ce3fd4 READING tag: ENTITY_PLANET_3_7_27_4_1440390867641.ent;  took long: 15ms
[2015-08-24 16:56:39] [SERVER] Planet(926)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Ship[ORP Tryton](927) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER] Ship[ORP Jastrzab](928) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER][RAIL] loaded rail relation from tag: RailRequest [rail={UniqueSegPiece [UNLOADED: ENTITY_SHIP_ORP Tryton](-10, 0, 15)[Rail Basic]o[FRONT][active][100hp][ja]}, docked={UniqueSegPiece [UNLOADED: ENTITY_SHIP_ORP Jastrzab](8, 7, 13)[Rail Docker]o[FRONT][active][100hp][ja]}, movedTransform=(0.0, 0.0, 0.0), sentFromServer=false, disconnect=false, dockingPermission=NORMAL]
[2015-08-24 16:56:39] [SERVER][LOADENTITY] WARNING: SHIP IN Sector[919](3, 7, 27) FROM TAG: ENTITY_SHIP_ORP Jastrzab.ent took long: 24ms
[2015-08-24 16:56:39] [SERVER] Ship[Armata 2](929) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER][RAIL] loaded rail relation from tag: RailRequest [rail={UniqueSegPiece [UNLOADED: ENTITY_SHIP_ORP Tryton](40, 1, -21)[Rail Basic]o[[WARNING] UNKNOWN SIDE 14][active][100hp][jq]}, docked={UniqueSegPiece [UNLOADED: ENTITY_SHIP_MOB_SIM_Isanth Type-Zero Cm_99_3](8, 1, 6)[Rail Docker]o[[WARNING] UNKNOWN SIDE 10][active][100hp][iW]}, movedTransform=(0.0, 0.0, 0.0), sentFromServer=false, disconnect=false, dockingPermission=NORMAL]
[2015-08-24 16:56:39] [SERVER] Planet(930)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(931)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(932)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER][READTAG] WARNING: org.schema.game.server.controller.GameServerController@46ce3fd4 READING tag: ENTITY_PLANET_3_7_27_0_1440390867633.ent;  took long: 13ms
[2015-08-24 16:56:39] [SERVER] Planet(933)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(934)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER] Planet(935)[s919]ENTITY_PLANETCORE_3_7_27(unloaded) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [UNIVERSE][PLANET] PLANET CORE ALREADY LOADED: PlanetCore( id 921; hp 1.0E7; uid ENTITY_PLANETCORE_3_7_27)
[2015-08-24 16:56:39] [SERVER][LOADENTITY] WARNING: PLANET IN Sector[919](3, 7, 27) FROM TAG: ENTITY_PLANET_3_7_27_3_1440390867638.ent took long: 37ms
[2015-08-24 16:56:39] [SERVER] Ship[Armata](936) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER][RAIL] loaded rail relation from tag: RailRequest [rail={UniqueSegPiece [UNLOADED: ENTITY_SHIP_O](9, 8, 8)[Rail Turret Axis]o[LEFT][inactive][100hp][ji]}, docked={UniqueSegPiece [UNLOADED: ENTITY_SHIP_Armata](10, 6, 9)[Rail Docker]o[BACK][inactive][100hp][jn]}, movedTransform=(0.0, 0.0, 0.0), sentFromServer=false, disconnect=false, dockingPermission=NORMAL]
[2015-08-24 16:56:39] [SERVER] Ship[O](937) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER][RAIL] loaded rail relation from tag: RailRequest [rail={UniqueSegPiece [UNLOADED: ENTITY_SHIP_ORP Tryton](8, 6, -57)[Rail Turret Axis]o[[WARNING] UNKNOWN SIDE 13][active][100hp][jr]}, docked={UniqueSegPiece [UNLOADED: ENTITY_SHIP_O](8, 7, 8)[Rail Docker]o[[WARNING] UNKNOWN SIDE 9][active][100hp][iX]}, movedTransform=(0.0, 0.0, 0.0), sentFromServer=false, disconnect=false, dockingPermission=NORMAL]
[2015-08-24 16:56:39] [SERVER] Ship[ORP Husky](938) loaded activation state: ActBuffer 0; Delay 0; DelayNR 0
[2015-08-24 16:56:39] [SERVER][UNIVERSE] WARNING: LOADING SECTOR TOOK SOME TIME... (3, 7, 27): STATS: inst 0ms, loadDB 7ms, add 0ms, entity 219ms
[2015-08-24 16:56:39] [SERVER] snap plCntrldObj Ship[Gravity_Unit_1440427670868](632) from (-780.30554, 2091.9258, 1252.9257) (original (-780.30554, 2091.9258, 1252.9257) [(-0.0016012898, 0.0, 2.6701517)] PhysObjNotNull? true) to (-748.59796, 2103.1216, 1260.0808); ATTACHED: false
[2015-08-24 16:56:39] [SERVER][UPDATE] WARNING: handleQueuedSynchronizedObjects update took 33 on Server(0)
[2015-08-24 16:56:39] [SERVER][SPAWN] SPAWNING NEW CHARACTER FOR PlS[Telepatos [Telepatos]*; id(769)(5)f(10001)]
[2015-08-24 16:56:39] [SERVER] spawn player character: doing sector switch to (3, 7, 27)
[2015-08-24 16:56:39] [SERVER][SPAWN] PlS[Telepatos [Telepatos]*; id(769)(5)f(10001)]; Relative: Planet(920)[s919]Planet  (r182)[10000000hp] -> (-131.79965, 117.28552, -115.28241); objOrigin: (-109.67826, 80.59459, -68.18214)
[2015-08-24 16:56:39] [SERVER][PlayerCharacter] Server(0) Set initial transform to (-131.79965, 117.28552, -115.28241)
[2015-08-24 16:56:39] SPAWNING WITH Ship[ORP Tryton](927): AICharacter[(ENTITY_CREATURE_1440273920508_0)(0)]
[2015-08-24 16:56:39] [CREATURE] Server(0) SETTING AFFINITY OF AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] FROM null to Ship[ORP Tryton](927) on Server(0)
[2015-08-24 16:56:39] [CREATURE] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] align on Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [SERVER][AI][ACTIVATE] Enter gravity of 927
[2015-08-24 16:56:39] SPAWNING WITH Ship[ORP Tryton](927): AICharacter[(ENTITY_CREATURE_1440273923000_1)(0)]
[2015-08-24 16:56:39] [CREATURE] Server(0) SETTING AFFINITY OF AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] FROM null to Ship[ORP Tryton](927) on Server(0)
[2015-08-24 16:56:39] [CREATURE] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] align on Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [SERVER][AI][ACTIVATE] Enter gravity of 927
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) EXECUTING RAIL REQUEST ON: Ship[Armata 2](929): (ConnectRequest: [UNLOADED: ENTITY_SHIP_MOB_SIM_Isanth Type-Zero Cm_99_3 (8, 1, 6) Rail Docker(663)] -> [UNLOADED: ENTITY_SHIP_ORP Tryton (40, 1, -21) Rail Basic(662)]
[2015-08-24 16:56:39] [RAIL][CONNECT] Server(0) Ship[Armata 2](929) DOCKING PROCEDURE SUCCESSFUL
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) SUCCESSFULLY EXECUTED RAIL REQUEST ON: Ship[Armata 2](929): (ConnectRequest: [Ship[Armata 2](929) (8, 1, 6) Rail Docker(663)] -> [Ship[Armata 2](929) (40, 1, -21) Rail Basic(662)]
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) EXECUTING RAIL REQUEST ON: Ship[Armata](936): (ConnectRequest: [UNLOADED: ENTITY_SHIP_Armata (10, 6, 9) Rail Docker(663)] -> [UNLOADED: ENTITY_SHIP_O (9, 8, 8) Rail Turret Axis(665)]
[2015-08-24 16:56:39] [RAIL][CONNECT] Server(0) Ship[Armata](936) DOCKING PROCEDURE SUCCESSFUL
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) SUCCESSFULLY EXECUTED RAIL REQUEST ON: Ship[Armata](936): (ConnectRequest: [Ship[Armata](936) (10, 6, 9) Rail Docker(663)] -> [Ship[Armata](936) (9, 8, 8) Rail Turret Axis(665)]
[2015-08-24 16:56:39] [AI] Setting callback Server(0) Ship[Armata](936) Executing send callback: true
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) EXECUTING RAIL REQUEST ON: Ship[O](937): (ConnectRequest: [UNLOADED: ENTITY_SHIP_O (8, 7, 8) Rail Docker(663)] -> [UNLOADED: ENTITY_SHIP_ORP Tryton (8, 6, -57) Rail Turret Axis(665)]
[2015-08-24 16:56:39] [RAIL][CONNECT] Server(0) Ship[O](937) DOCKING PROCEDURE SUCCESSFUL
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) SUCCESSFULLY EXECUTED RAIL REQUEST ON: Ship[O](937): (ConnectRequest: [Ship[O](937) (8, 7, 8) Rail Docker(663)] -> [Ship[O](937) (8, 6, -57) Rail Turret Axis(665)]
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) EXECUTING RAIL REQUEST ON: Ship[ORP Jastrzab](928): (ConnectRequest: [UNLOADED: ENTITY_SHIP_ORP Jastrzab (8, 7, 13) Rail Docker(663)] -> [UNLOADED: ENTITY_SHIP_ORP Tryton (-10, 0, 15) Rail Basic(662)]
[2015-08-24 16:56:39] [RAIL][CONNECT] Server(0) Ship[ORP Jastrzab](928) DOCKING PROCEDURE SUCCESSFUL
[2015-08-24 16:56:39] [RAIL][UPDATE] Server(0) SUCCESSFULLY EXECUTED RAIL REQUEST ON: Ship[ORP Jastrzab](928): (ConnectRequest: [Ship[ORP Jastrzab](928) (8, 7, 13) Rail Docker(663)] -> [Ship[ORP Jastrzab](928) (-10, 0, 15) Rail Basic(662)]
[2015-08-24 16:56:39] SPAWNING WITH Planet(920)[s919]Planet Zoltar (r182)[10000000hp]: AICharacter[(ENTITY_CREATURE_1440334821333_8)(0)]
[2015-08-24 16:56:39] [CREATURE] Server(0) SETTING AFFINITY OF AICharacter[(ENTITY_CREATURE_1440334821333_8)(942)] FROM null to Planet(920)[s919]Planet Zoltar (r182)[10000000hp] on Server(0)
[2015-08-24 16:56:39] [CREATURE] AICharacter[(ENTITY_CREATURE_1440334821333_8)(942)] align on Planet(920)[s919]Planet Zoltar (r182)[10000000hp]
[2015-08-24 16:56:39] [SERVER][AI][ACTIVATE] Enter gravity of 920
[2015-08-24 16:56:39] [SERVER][UPDATE] WARNING: object local update of Planet(920)[s919]Planet Zoltar (r182)[10000000hp] took 116
[2015-08-24 16:56:39] [SERVER][UPDATE] WARNING: object local update of 100 objects took 129
[2015-08-24 16:56:39] [SERVER] SERIALIZED PROXIMITY (3, 7, 27)
[2015-08-24 16:56:39] Server(0) AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] HANDLE SCHEDULED GRAVITY (0.0, 0.0, 0.0), Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [GRAVITY] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] Server(0) SOURCE CHANGE null -> Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [SIMPLETRANSFORMABLE] Server(0) AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] gravity change sent
[2015-08-24 16:56:39] [GRAVITY][Server(0)] gravity reset for AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] -> Ship[ORP Tryton](927): AABB test failed: Own: (-1824.0157, 1299.9631, -1591.281); (-1775.2755, 1348.947, -1534.8284)  ----  Source (238.17181, -260.1412, -672.4413); (238.60474, -259.7061, -671.9398) of Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [GRAVITY] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] changed gravity on Server(0) Ship[ORP Tryton](927) -> (0.0, 0.0, 0.0)
[2015-08-24 16:56:39] [CHARACTER][GRAVITY] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] Server(0) starting gravity change: (-1799.6456, 1324.4551, -1563.0547)
[2015-08-24 16:56:39] Server(0)[ABSTRACTCHARACTER] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] INITIALIZING GRAVITY FOR Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [PLAYERCHARACTER] Server(0) of AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] gravity to: [WARNING] UNKNOWN SIDE -1
[2015-08-24 16:56:39] [CHARACTER][GRAVITY] AICharacter[(ENTITY_CREATURE_1440273920508_0)(940)] Server(0) starting gravity change DONE: source: Ship[ORP Tryton](927) origin: (-1799.6458, 1324.4551, -1563.0547)
[2015-08-24 16:56:39] [RAIL] Server(0) RECREATING RAIL FROM ROOT: Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [RAIL] Server(0) removeObjectPhysicsRecusively() removed physics for Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [RAIL] Server(0) removeObjectPhysicsRecusively() removed physics for Ship[Armata 2](929)
[2015-08-24 16:56:39] [RAIL] Server(0) removeObjectPhysicsRecusively() removed physics for Ship[O](937)
[2015-08-24 16:56:39] [RAIL] Server(0) removeObjectPhysicsRecusively() removed physics for Ship[Armata](936)
[2015-08-24 16:56:39] [RAIL] Server(0) removeObjectPhysicsRecusively() removed physics for Ship[ORP Jastrzab](928)
[2015-08-24 16:56:39] [RAIL] Server(0) recreateRootObjectPhysics() added physics for Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [RAIL] Server(0) RECREATING RAIL FROM ROOT DONE: Ship[ORP Tryton](927); DOCKED AND EXECUTED: false; DOCKED OR DIRTY: false
[2015-08-24 16:56:39] Server(0) AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] HANDLE SCHEDULED GRAVITY (0.0, 0.0, 0.0), Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [GRAVITY] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] Server(0) SOURCE CHANGE null -> Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [SIMPLETRANSFORMABLE] Server(0) AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] gravity change sent
[2015-08-24 16:56:39] [GRAVITY][Server(0)] gravity reset for AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] -> Ship[ORP Tryton](927): AABB test failed: Own: (-1634.9215, -2125.6226, -655.93555); (-1586.1813, -2076.6387, -599.4829)  ----  Source (215.89128, -296.6031, -732.8065); (296.39447, -248.85075, -644.83545) of Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [GRAVITY] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] changed gravity on Server(0) Ship[ORP Tryton](927) -> (0.0, 0.0, 0.0)
[2015-08-24 16:56:39] [CHARACTER][GRAVITY] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] Server(0) starting gravity change: (-1610.5514, -2101.1306, -627.7092)
[2015-08-24 16:56:39] Server(0)[ABSTRACTCHARACTER] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] INITIALIZING GRAVITY FOR Ship[ORP Tryton](927)
[2015-08-24 16:56:39] [PLAYERCHARACTER] Server(0) of AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] gravity to: [WARNING] UNKNOWN SIDE -1
[2015-08-24 16:56:39] [CHARACTER][GRAVITY] AICharacter[(ENTITY_CREATURE_1440273923000_1)(941)] Server(0) starting gravity change DONE: source: Ship[ORP Tryton](927) origin: (-1610.5514, -2101.1306, -627.7092)
[2015-08-24 16:56:39] Server(0) AICharacter[(ENTITY_CREATURE_1440334821333_8)(942)] HANDLE SCHEDULED GRAVITY (0.0, 0.0, 0.0), Planet(920)[s919]Planet Zoltar (r182)[10000000hp]
[2015-08-24 16:56:39] [GRAVITY] AICharacter[(ENTITY_CREATURE_1440334821333_8)(942)] Server(0) SOURCE CHANGE null -> Planet(920)[s919]Planet Zoltar (r182)[10000000hp]
[2015-08-24 16:56:39] [SIMPLETRANSFORMABLE] Server(0) AICharacter[(ENTITY_CREATURE_1440334821333_8)(942)] gravity change sent

LINES BEFORE EXCEPTION ^^^

[2015-08-24 16:56:39] Exception in thread "ServerController" 
[2015-08-24 16:56:39] java.lang.AbstractMethodError: java.lang.Exception.printStackTrace()V
[2015-08-24 16:56:39] 	at org.schema.game.server.controller.GameServerController.update(SourceFile:1163)
[2015-08-24 16:56:39] 	at org.schema.schine.network.server.ServerController.run(SourceFile:253)
[2015-08-24 16:56:39] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 16:56:49] [SERVERSOCKET] Connection made. starting new processor 59494, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:56:49] [SERVER] connection registered (TRunning: 4/4/4) 58
[2015-08-24 16:56:49] [SERVER][PROCESSOR] client setup completed for PID: 58. listening for input
[2015-08-24 16:56:49] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 58
[2015-08-24 16:56:49] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:56:49] [SERVER] Executing scheduled disconnect!; ProcessorID: 58
[2015-08-24 16:56:49] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 58
[2015-08-24 16:56:49] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 58
[2015-08-24 16:57:06] [SERVERSOCKET] Connection made. starting new processor 49658, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:57:06] [SERVER] connection registered (TRunning: 4/4/4) 59
[2015-08-24 16:57:06] [SERVER][PROCESSOR] client setup completed for PID: 59. listening for input
[2015-08-24 16:57:06] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 59
[2015-08-24 16:57:06] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:57:06] [SERVER] Executing scheduled disconnect!; ProcessorID: 59
[2015-08-24 16:57:06] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 59
[2015-08-24 16:57:06] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 59
[2015-08-24 16:57:13] [SERVERSOCKET] Connection made. starting new processor 54047, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:57:13] [SERVER] connection registered (TRunning: 4/4/4) 60
[2015-08-24 16:57:13] [SERVER][PROCESSOR] client setup completed for PID: 60. listening for input
[2015-08-24 16:57:13] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 60
[2015-08-24 16:57:13] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:57:13] [SERVER] Executing scheduled disconnect!; ProcessorID: 60
[2015-08-24 16:57:13] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 60
[2015-08-24 16:57:13] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 60
[2015-08-24 16:57:21] [SERVERSOCKET] Connection made. starting new processor 50237, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:57:21] [SERVER] connection registered (TRunning: 4/4/4) 61
[2015-08-24 16:57:21] [SERVER][PROCESSOR] client setup completed for PID: 61. listening for input
[2015-08-24 16:57:21] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 61
[2015-08-24 16:57:21] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:57:21] [SERVER] Executing scheduled disconnect!; ProcessorID: 61
[2015-08-24 16:57:21] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 61
[2015-08-24 16:57:21] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 61
[2015-08-24 16:57:35] [SERVERPROCESSOR][WARNING} PING timeout warning. resending ping to RegisteredClient: Gravity_Unit (4) [explodealln00bs@gmail.com]connected: true Retries left: 12; socket connected: true; socket closed: false; inputShutdown: false; outputShutdown: false; ProcessorID: 33
[2015-08-24 16:57:35] [SERVERPROCESSOR][WARNING} PING has been resent to RegisteredClient: Gravity_Unit (4) [XXXXXX@gmail.com]connected: true
[2015-08-24 16:57:36] RETRY STATUS: Retries: 11; waiting for pong true (1440428256376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:37] RETRY STATUS: Retries: 11; waiting for pong true (1440428257376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:38] RETRY STATUS: Retries: 11; waiting for pong true (1440428258376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:38] [SERVERSOCKET] Connection made. starting new processor 64847, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:57:38] [SERVER] connection registered (TRunning: 4/4/4) 62
[2015-08-24 16:57:38] [SERVER][PROCESSOR] client setup completed for PID: 62. listening for input
[2015-08-24 16:57:38] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX PID: 62
[2015-08-24 16:57:38] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:57:38] [SERVER] Executing scheduled disconnect!; ProcessorID: 62
[2015-08-24 16:57:38] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 62
[2015-08-24 16:57:38] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 62
[2015-08-24 16:57:39] RETRY STATUS: Retries: 11; waiting for pong true (1440428259376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:40] RETRY STATUS: Retries: 11; waiting for pong true (1440428260376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:41] RETRY STATUS: Retries: 11; waiting for pong true (1440428261376/1440428265375); ProcessorID: 33
[2015-08-24 16:57:42] RETRY STATUS: Retries: 11; waiting for pong true (1440428262377/1440428265375); ProcessorID: 33
[2015-08-24 16:57:43] RETRY STATUS: Retries: 11; waiting for pong true (1440428263377/1440428265375); ProcessorID: 33
[2015-08-24 16:57:44] RETRY STATUS: Retries: 11; waiting for pong true (1440428264377/1440428265375); ProcessorID: 33
[2015-08-24 16:57:45] [SERVERPROCESSOR][WARNING} PING timeout warning. resending ping to RegisteredClient: Gravity_Unit (4) [XXXXX@gmail.com]connected: true Retries left: 11; socket connected: true; socket closed: false; inputShutdown: false; outputShutdown: false; ProcessorID: 33
[2015-08-24 16:57:45] [SERVERPROCESSOR][WARNING} PING has been resent to RegisteredClient: Gravity_Unit (4) [XXXXXX@gmail.com]connected: true
[2015-08-24 16:57:45] Exception happened with size 85
[2015-08-24 16:57:45] [SERVER] NTException on: RegisteredClient: Gravity_Unit (4) [XXXXXX@gmail.com]connected: true; ProcessorID: 33
[2015-08-24 16:57:45] [SERVER] last received size: 85
[2015-08-24 16:57:45] [SERVER] last received check: 42
[2015-08-24 16:57:45] [SERVER] last received header: 123: Stream
[2015-08-24 16:57:45] [SERVER] last received command: Synchronize[void]
[2015-08-24 16:57:45] [SERVER][LASTRECEIVED] decoded class #0: NetworkShip; decoded: [(RemotePhysicsTransform; val: org.schema.schine.network.objects.NetworkTransformation@7a5f07e3)]
[2015-08-24 16:57:45] [SERVER][LASTRECEIVED] decoded class #1: NetworkPlayer; decoded: [(r(-0.42457664, -0.34730008, -0.18297693, 0.81586564))]
[2015-08-24 16:57:45] java.net.SocketException: Connection reset
[2015-08-24 16:57:45] 	at java.net.SocketInputStream.read(SocketInputStream.java:209)
[2015-08-24 16:57:45] 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
[2015-08-24 16:57:45] 	at java.io.DataInputStream.readFully(DataInputStream.java:195)
[2015-08-24 16:57:45] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:518)
[2015-08-24 16:57:45] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 16:57:45] [SERVER][DISCONNECT] Client 'RegisteredClient: Gravity_Unit (4) [XXXXX@gmail.com]connected: true' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 33
[2015-08-24 16:57:45] [SERVER] Client <4> logged out from server. connections count: 60
[2015-08-24 16:57:45] [SERVER] UNREGISTERING CLIENT RegisteredClient: Gravity_Unit (4) [XXXXX@gmail.com]connected: true
[2015-08-24 16:57:45] [SERVER] UNREGISTER DONE FOR CLIENT RegisteredClient: Gravity_Unit (4) [XXXXX@gmail.com]connected: true
[2015-08-24 16:57:45] [SERVER] SERVER PROCESSOR STOPPED FOR RegisteredClient: Gravity_Unit (4) [XXXX@gmail.com]connected: true; PID 33
[2015-08-24 16:57:51] [SERVERSOCKET] Connection made. starting new processor 55978, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:57:51] [SERVER] connection registered (TRunning: 3/3/3) 63
[2015-08-24 16:57:51] [SERVER][PROCESSOR] client setup completed for PID: 63. listening for input
[2015-08-24 16:57:51] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 63
[2015-08-24 16:57:51] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:57:51] [SERVER] Executing scheduled disconnect!; ProcessorID: 63
[2015-08-24 16:57:51] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 63
[2015-08-24 16:57:51] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 63
[2015-08-24 16:58:21] [SERVERSOCKET] Connection made. starting new processor 64276, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:58:21] [SERVER] connection registered (TRunning: 3/3/3) 64
[2015-08-24 16:58:21] [SERVER][PROCESSOR] client setup completed for PID: 64. listening for input
[2015-08-24 16:58:21] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 64
[2015-08-24 16:58:21] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:58:21] [SERVER] Executing scheduled disconnect!; ProcessorID: 64
[2015-08-24 16:58:21] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 64
[2015-08-24 16:58:21] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 64
[2015-08-24 16:58:23] [SERVERSOCKET] Connection made. starting new processor 65046, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:58:23] [SERVER] connection registered (TRunning: 3/3/3) 65
[2015-08-24 16:58:23] [SERVER][PROCESSOR] client setup completed for PID: 65. listening for input
[2015-08-24 16:58:23] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 65
[2015-08-24 16:58:23] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:58:23] [SERVER] Executing scheduled disconnect!; ProcessorID: 65
[2015-08-24 16:58:23] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 65
[2015-08-24 16:58:23] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 65
[2015-08-24 16:58:35] [SERVERSOCKET] Connection made. starting new processor 64413, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:58:35] [SERVER] connection registered (TRunning: 3/3/3) 66
[2015-08-24 16:58:35] [SERVER][PROCESSOR] client setup completed for PID: 66. listening for input
[2015-08-24 16:58:35] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 66
[2015-08-24 16:58:35] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:58:35] [SERVER] Executing scheduled disconnect!; ProcessorID: 66
[2015-08-24 16:58:35] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 66
[2015-08-24 16:58:35] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 66
[2015-08-24 16:59:27] [SERVERSOCKET] Connection made. starting new processor 49875, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 16:59:27] [SERVER] connection registered (TRunning: 3/3/3) 67
[2015-08-24 16:59:27] [SERVER][PROCESSOR] client setup completed for PID: 67. listening for input
[2015-08-24 16:59:27] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 67
[2015-08-24 16:59:27] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 16:59:27] [SERVER] Executing scheduled disconnect!; ProcessorID: 67
[2015-08-24 16:59:27] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 67
[2015-08-24 16:59:27] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 67
[2015-08-24 17:00:01] [SERVER] NTException on: RegisteredClient: Telepatos (5) [Telepatos]connected: true; ProcessorID: 54
[2015-08-24 17:00:01] [SERVER] last received size: 2
[2015-08-24 17:00:01] [SERVER] last received check: 23
[2015-08-24 17:00:01] [SERVER] last received header: 111: Param
[2015-08-24 17:00:01] [SERVER] last received command: RequestServerTime[void]
[2015-08-24 17:00:01] [SERVER][LASTRECEIVED] decoded class #0: NetworkPlayer; decoded: [(r(-0.013289349, 0.0053591556, 0.0021840474, 0.7070325))]
[2015-08-24 17:00:01] java.io.EOFException
[2015-08-24 17:00:01] 	at java.io.DataInputStream.readInt(DataInputStream.java:392)
[2015-08-24 17:00:01] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:507)
[2015-08-24 17:00:01] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 17:00:01] [SERVER][DISCONNECT] Client 'RegisteredClient: Telepatos (5) [Telepatos]connected: true' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 54
[2015-08-24 17:00:01] [SERVER] Client <5> logged out from server. connections count: 64
[2015-08-24 17:00:01] [SERVER] UNREGISTERING CLIENT RegisteredClient: Telepatos (5) [Telepatos]connected: true
[2015-08-24 17:00:01] [SERVER] UNREGISTER DONE FOR CLIENT RegisteredClient: Telepatos (5) [Telepatos]connected: true
[2015-08-24 17:00:01] [SERVER] SERVER PROCESSOR STOPPED FOR RegisteredClient: Telepatos (5) [Telepatos]connected: true; PID 54
[2015-08-24 17:00:17] [SERVERSOCKET] Connection made. starting new processor 49968, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:00:17] [SERVER] connection registered (TRunning: 2/2/2) 68
[2015-08-24 17:00:17] [SERVER][PROCESSOR] client setup completed for PID: 68. listening for input
[2015-08-24 17:00:17] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX PID: 68
[2015-08-24 17:00:17] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:00:17] [SERVER] Executing scheduled disconnect!; ProcessorID: 68
[2015-08-24 17:00:17] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 68
[2015-08-24 17:00:17] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 68
[2015-08-24 17:00:28] [SERVERSOCKET] Connection made. starting new processor 3847, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:00:28] [SERVER] connection registered (TRunning: 2/2/2) 69
[2015-08-24 17:00:28] [SERVER][PROCESSOR] client setup completed for PID: 69. listening for input
[2015-08-24 17:00:28] [SERVER][LOGIN] new client connected. given id: 6: description: Telepatos
[2015-08-24 17:00:28] [SERVER][LOGIN] return code 0
[2015-08-24 17:00:30] [SERVER] client has not send any login information: 2000 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:31] [SERVER] client has not send any login information: 3001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:32] [SERVER] client has not send any login information: 4001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:33] [SERVER] client has not send any login information: 5001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:34] [SERVER] client has not send any login information: 6001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:35] [SERVER] client has not send any login information: 7001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:36] [SERVER] client has not send any login information: 8001 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:37] [SERVER] client has not send any login information: 9002 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:38] [SERVER] client has not send any login information: 10002 / 10000 ms; ProcessorID: 69
[2015-08-24 17:00:38] [SERVER] NULL CLIENT TIMED OUT: DISCONENCTING; ProcessorID: 69
[2015-08-24 17:00:38] [SERVER] NTException on: null; ProcessorID: 69
[2015-08-24 17:00:38] [SERVER] last received size: 73
[2015-08-24 17:00:38] [SERVER] last received check: 42
[2015-08-24 17:00:38] [SERVER] last received header: 111: Param
[2015-08-24 17:00:38] [SERVER] last received command: Login[return]
[2015-08-24 17:00:38] java.net.SocketException: Socket closed
[2015-08-24 17:00:38] 	at java.net.SocketInputStream.read(SocketInputStream.java:203)
[2015-08-24 17:00:38] 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
[2015-08-24 17:00:38] 	at java.net.SocketInputStream.read(SocketInputStream.java:223)
[2015-08-24 17:00:38] 	at java.io.DataInputStream.readInt(DataInputStream.java:387)
[2015-08-24 17:00:38] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:507)
[2015-08-24 17:00:38] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 17:00:38] [SERVER][DISCONNECT] Client 'null' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 69
[2015-08-24 17:00:38] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:00:38] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:00:38] [SERVER] SERVER PROCESSOR STOPPED FOR null; PID 69
[2015-08-24 17:00:47] [SIMULATION] checking sectors to plan activity.... (-1, 3, 23) to (7, 11, 31)
[2015-08-24 17:00:55] [SERVERSOCKET] Connection made. starting new processor 50316, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:00:55] [SERVER] connection registered (TRunning: 2/2/2) 70
[2015-08-24 17:00:55] [SERVER][PROCESSOR] client setup completed for PID: 70. listening for input
[2015-08-24 17:00:55] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 70
[2015-08-24 17:00:55] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:00:55] [SERVER] Executing scheduled disconnect!; ProcessorID: 70
[2015-08-24 17:00:55] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 70
[2015-08-24 17:00:55] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 70
[2015-08-24 17:01:43] [SERVERSOCKET] Connection made. starting new processor 49369, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:01:43] [SERVER] connection registered (TRunning: 2/2/2) 71
[2015-08-24 17:01:43] [SERVER][PROCESSOR] client setup completed for PID: 71. listening for input
[2015-08-24 17:01:43] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 71
[2015-08-24 17:01:43] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:01:43] [SERVER] Executing scheduled disconnect!; ProcessorID: 71
[2015-08-24 17:01:43] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 71
[2015-08-24 17:01:43] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 71
[2015-08-24 17:01:56] [SERVERSOCKET] Connection made. starting new processor 52080, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:01:56] [SERVER] connection registered (TRunning: 2/2/2) 72
[2015-08-24 17:01:56] [SERVER][PROCESSOR] client setup completed for PID: 72. listening for input
[2015-08-24 17:01:56] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 72
[2015-08-24 17:01:56] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:01:56] [SERVER] Executing scheduled disconnect!; ProcessorID: 72
[2015-08-24 17:01:56] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 72
[2015-08-24 17:01:56] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 72
[2015-08-24 17:02:07] [SERVERSOCKET] Connection made. starting new processor 53093, /XXX.XXX.XXX.XXX3; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:02:07] [SERVER] connection registered (TRunning: 2/2/2) 73
[2015-08-24 17:02:07] [SERVER][PROCESSOR] client setup completed for PID: 73. listening for input
[2015-08-24 17:02:07] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 73
[2015-08-24 17:02:07] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:02:07] [SERVER] Executing scheduled disconnect!; ProcessorID: 73
[2015-08-24 17:02:07] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 73
[2015-08-24 17:02:07] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 73
[2015-08-24 17:02:07] [SERVERSOCKET] Connection made. starting new processor 49496, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:02:07] [SERVER] connection registered (TRunning: 2/3/2) 74
[2015-08-24 17:02:07] [SERVER][PROCESSOR] client setup completed for PID: 74. listening for input
[2015-08-24 17:02:07] [SERVER] This client is an info ping (server-lists): /89.11.153.132; PID: 74
[2015-08-24 17:02:07] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:02:07] [SERVER] Executing scheduled disconnect!; ProcessorID: 74
[2015-08-24 17:02:07] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 74
[2015-08-24 17:02:07] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 74

A LOT OF REPEATS ^^^^

[2015-08-24 17:07:07] [SERVERSOCKET] Connection made. starting new processor 49776, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:07:07] [SERVER] connection registered (TRunning: 2/2/2) 91
[2015-08-24 17:07:07] [SERVER][PROCESSOR] client setup completed for PID: 91. listening for input
[2015-08-24 17:07:07] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 91
[2015-08-24 17:07:07] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:07:07] [SERVER] Executing scheduled disconnect!; ProcessorID: 91
[2015-08-24 17:07:07] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 91
[2015-08-24 17:07:07] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 91
[2015-08-24 17:07:08] [SERVERSOCKET] Connection made. starting new processor 3856, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:07:08] [SERVER] connection registered (TRunning: 2/3/2) 92
[2015-08-24 17:07:08] [SERVER][PROCESSOR] client setup completed for PID: 92. listening for input
[2015-08-24 17:07:08] [SERVER][LOGIN] new client connected. given id: 7: description: Telepatos
[2015-08-24 17:07:08] [SERVER][LOGIN] return code 0
[2015-08-24 17:07:10] [SERVER] client has not send any login information: 2000 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:11] [SERVER] client has not send any login information: 3001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:12] [SERVER] client has not send any login information: 4001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:13] [SERVER] client has not send any login information: 5001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:14] [SERVER] client has not send any login information: 6001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:15] [SERVER] client has not send any login information: 7001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:16] [SERVER] client has not send any login information: 8001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:17] [SERVER] client has not send any login information: 9001 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:18] [SERVER] client has not send any login information: 10002 / 10000 ms; ProcessorID: 92
[2015-08-24 17:07:18] [SERVER] NULL CLIENT TIMED OUT: DISCONENCTING; ProcessorID: 92
[2015-08-24 17:07:18] [SERVER] NTException on: null; ProcessorID: 92
[2015-08-24 17:07:18] [SERVER] last received size: 73
[2015-08-24 17:07:18] [SERVER] last received check: 42
[2015-08-24 17:07:18] [SERVER] last received header: 111: Param
[2015-08-24 17:07:18] [SERVER] last received command: Login[return]
[2015-08-24 17:07:18] java.net.SocketException: Socket closed
[2015-08-24 17:07:18] 	at java.net.SocketInputStream.read(SocketInputStream.java:203)
[2015-08-24 17:07:18] 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
[2015-08-24 17:07:18] 	at java.net.SocketInputStream.read(SocketInputStream.java:223)
[2015-08-24 17:07:18] 	at java.io.DataInputStream.readInt(DataInputStream.java:387)
[2015-08-24 17:07:18] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:507)
[2015-08-24 17:07:18] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 17:07:18] [SERVER][DISCONNECT] Client 'null' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 92
[2015-08-24 17:07:18] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:07:18] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:07:18] [SERVER] SERVER PROCESSOR STOPPED FOR null; PID 92
[2015-08-24 17:07:21] [SERVERSOCKET] Connection made. starting new processor 61670, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:07:21] [SERVER] connection registered (TRunning: 2/2/2) 93
[2015-08-24 17:07:21] [SERVER][PROCESSOR] client setup completed for PID: 93. listening for input
[2015-08-24 17:07:21] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX PID: 93
[2015-08-24 17:07:21] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:07:21] [SERVER] Executing scheduled disconnect!; ProcessorID: 93
[2015-08-24 17:07:21] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 93
[2015-08-24 17:07:21] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 93
[2015-08-24 17:07:47] [SIMULATION] checking sectors to plan activity.... (-1, 3, 23) to (7, 11, 31)
[2015-08-24 17:07:52] [SERVERSOCKET] Connection made. starting new processor 56869, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:07:52] [SERVER] connection registered (TRunning: 2/2/2) 94
[2015-08-24 17:07:52] [SERVER][PROCESSOR] client setup completed for PID: 94. listening for input
[2015-08-24 17:07:52] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 94
[2015-08-24 17:07:52] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:07:52] [SERVER] Executing scheduled disconnect!; ProcessorID: 94
[2015-08-24 17:07:52] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 94
[2015-08-24 17:07:52] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 94
[2015-08-24 17:08:01] [SERVERSOCKET] Connection made. starting new processor 52814, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:08:01] [SERVER] connection registered (TRunning: 2/2/2) 95
[2015-08-24 17:08:01] [SERVER][PROCESSOR] client setup completed for PID: 95. listening for input
[2015-08-24 17:08:01] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX PID: 95
[2015-08-24 17:08:01] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:08:01] [SERVER] Executing scheduled disconnect!; ProcessorID: 95
[2015-08-24 17:08:01] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 95
[2015-08-24 17:08:01] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 95
[2015-08-24 17:08:12] [SERVERSOCKET] Connection made. starting new processor 51050, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:08:12] [SERVER] connection registered (TRunning: 2/2/2) 96
[2015-08-24 17:08:12] [SERVER][PROCESSOR] client setup completed for PID: 96. listening for input
[2015-08-24 17:08:12] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 96
[2015-08-24 17:08:12] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:08:12] [SERVER] Executing scheduled disconnect!; ProcessorID: 96
[2015-08-24 17:08:12] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 96
[2015-08-24 17:08:12] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 96
[2015-08-24 17:09:45] [SERVERSOCKET] Connection made. starting new processor 3850, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:09:45] [SERVER] connection registered (TRunning: 2/2/2) 97
[2015-08-24 17:09:45] [SERVER][PROCESSOR] client setup completed for PID: 97. listening for input
[2015-08-24 17:09:45] [SERVER][LOGIN] new client connected. given id: 8: description: Telepatos
[2015-08-24 17:09:45] [SERVER][LOGIN] return code 0
[2015-08-24 17:09:47] [SERVER] client has not send any login information: 2000 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:48] [SERVER] client has not send any login information: 3000 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:49] [SERVER] client has not send any login information: 4001 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:50] [SERVER] client has not send any login information: 5001 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:51] [SERVER] client has not send any login information: 6002 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:52] [SERVER] client has not send any login information: 7002 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:53] [SERVER] client has not send any login information: 8002 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:54] [SERVER] client has not send any login information: 9002 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:55] [SERVER] client has not send any login information: 10002 / 10000 ms; ProcessorID: 97
[2015-08-24 17:09:55] [SERVER] NULL CLIENT TIMED OUT: DISCONENCTING; ProcessorID: 97
[2015-08-24 17:09:55] [SERVER] NTException on: null; ProcessorID: 97
[2015-08-24 17:09:55] [SERVER] last received size: 73
[2015-08-24 17:09:55] [SERVER] last received check: 42
[2015-08-24 17:09:55] [SERVER] last received header: 111: Param
[2015-08-24 17:09:55] [SERVER] last received command: Login[return]
[2015-08-24 17:09:55] java.net.SocketException: Socket closed
[2015-08-24 17:09:55] 	at java.net.SocketInputStream.read(SocketInputStream.java:203)
[2015-08-24 17:09:55] 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
[2015-08-24 17:09:55] 	at java.net.SocketInputStream.read(SocketInputStream.java:223)
[2015-08-24 17:09:55] 	at java.io.DataInputStream.readInt(DataInputStream.java:387)
[2015-08-24 17:09:55] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:507)
[2015-08-24 17:09:55] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 17:09:55] [SERVER][DISCONNECT] Client 'null' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 97
[2015-08-24 17:09:55] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:09:55] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:09:55] [SERVER] SERVER PROCESSOR STOPPED FOR null; PID 97
[2015-08-24 17:10:49] [SERVERSOCKET] Connection made. starting new processor 1908, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:10:49] [SERVER] connection registered (TRunning: 2/2/2) 98
[2015-08-24 17:10:49] [SERVER][PROCESSOR] client setup completed for PID: 98. listening for input
[2015-08-24 17:10:49] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 98
[2015-08-24 17:10:49] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:10:49] [SERVER] Executing scheduled disconnect!; ProcessorID: 98
[2015-08-24 17:10:49] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 98
[2015-08-24 17:10:49] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 98
[2015-08-24 17:10:58] [SERVERSOCKET] Connection made. starting new processor 2020, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:10:58] [SERVER] connection registered (TRunning: 2/2/2) 99
[2015-08-24 17:10:58] [SERVER][PROCESSOR] client setup completed for PID: 99. listening for input
[2015-08-24 17:10:58] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX PID: 99
[2015-08-24 17:10:58] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:10:58] [SERVER] Executing scheduled disconnect!; ProcessorID: 99
[2015-08-24 17:10:58] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 99
[2015-08-24 17:10:58] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 99
[2015-08-24 17:11:09] [SERVERSOCKET] Connection made. starting new processor 49983, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:11:09] [SERVER] connection registered (TRunning: 2/2/2) 100
[2015-08-24 17:11:09] [SERVER][PROCESSOR] client setup completed for PID: 100. listening for input
[2015-08-24 17:11:09] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 100
[2015-08-24 17:11:09] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:11:09] [SERVER] Executing scheduled disconnect!; ProcessorID: 100
[2015-08-24 17:11:09] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 100
[2015-08-24 17:11:09] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 100
[2015-08-24 17:11:28] [SERVERSOCKET] Connection made. starting new processor 56791, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:11:28] [SERVER] connection registered (TRunning: 2/2/2) 101
[2015-08-24 17:11:28] [SERVER][PROCESSOR] client setup completed for PID: 101. listening for input
[2015-08-24 17:11:28] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 101
[2015-08-24 17:11:28] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:11:28] [SERVER] Executing scheduled disconnect!; ProcessorID: 101
[2015-08-24 17:11:28] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 101
[2015-08-24 17:11:28] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 101
[2015-08-24 17:11:35] [SERVERSOCKET] Connection made. starting new processor 52745, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:11:35] [SERVER] connection registered (TRunning: 2/2/2) 102
[2015-08-24 17:11:35] [SERVER][PROCESSOR] client setup completed for PID: 102. listening for input
[2015-08-24 17:11:35] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 102
[2015-08-24 17:11:35] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:11:35] [SERVER] Executing scheduled disconnect!; ProcessorID: 102
[2015-08-24 17:11:35] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 102
[2015-08-24 17:11:35] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 102
[2015-08-24 17:11:41] [SERVERSOCKET] Connection made. starting new processor 52877, /XXX.XXX.XXX.XXX; local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:11:41] [SERVER] connection registered (TRunning: 2/2/2) 103
[2015-08-24 17:11:41] [SERVER][PROCESSOR] client setup completed for PID: 103. listening for input
[2015-08-24 17:11:41] [SERVER] This client is an info ping (server-lists): /XXX.XXX.XXX.XXX; PID: 103
[2015-08-24 17:11:41] [SERVER] SCHEDULED DISCONNECT EXECUTING
[2015-08-24 17:11:41] [SERVER] Executing scheduled disconnect!; ProcessorID: 103
[2015-08-24 17:11:41] [SERVER][DISCONNECT] Client 'Info-Pinger (server-lists)' HAS BEEN DISCONNECTED . PROBE: true; ProcessorID: 103
[2015-08-24 17:11:41] [SERVER] PROBE SUCCESSFULLY EXECUTED. STOPPING PROCESSOR. (Ping of a Starter to start server): PID 103

ALL LOT OF REPEATS ^^^^

[2015-08-24 17:15:05] [SERVERSOCKET] Connection made. starting new processor 3847, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:15:05] [SERVER] connection registered (TRunning: 2/2/2) 113
[2015-08-24 17:15:05] [SERVER][PROCESSOR] client setup completed for PID: 113. listening for input
[2015-08-24 17:15:05] [SERVER][LOGIN] new client connected. given id: 9: description: Telepatos
[2015-08-24 17:15:05] [SERVER][LOGIN] return code 0
[2015-08-24 17:15:07] [SERVER] client has not send any login information: 2000 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:08] [SERVER] client has not send any login information: 3000 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:09] [SERVER] client has not send any login information: 4000 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:10] [SERVER] client has not send any login information: 5000 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:11] [SERVER] client has not send any login information: 6000 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:12] [SERVER] client has not send any login information: 7001 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:13] [SERVER] client has not send any login information: 8001 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:14] [SERVER] client has not send any login information: 9001 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:15] [SERVER] client has not send any login information: 10001 / 10000 ms; ProcessorID: 113
[2015-08-24 17:15:15] [SERVER] NULL CLIENT TIMED OUT: DISCONENCTING; ProcessorID: 113
[2015-08-24 17:15:15] [SERVER] NTException on: null; ProcessorID: 113
[2015-08-24 17:15:15] [SERVER] last received size: 73
[2015-08-24 17:15:15] [SERVER] last received check: 42
[2015-08-24 17:15:15] [SERVER] last received header: 111: Param
[2015-08-24 17:15:15] [SERVER] last received command: Login[return]
[2015-08-24 17:15:15] java.net.SocketException: Socket closed
[2015-08-24 17:15:15] 	at java.net.SocketInputStream.read(SocketInputStream.java:203)
[2015-08-24 17:15:15] 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
[2015-08-24 17:15:15] 	at java.net.SocketInputStream.read(SocketInputStream.java:223)
[2015-08-24 17:15:15] 	at java.io.DataInputStream.readInt(DataInputStream.java:387)
[2015-08-24 17:15:15] 	at org.schema.schine.network.server.ServerProcessor.run(SourceFile:507)
[2015-08-24 17:15:15] 	at java.lang.Thread.run(Thread.java:745)
[2015-08-24 17:15:15] [SERVER][DISCONNECT] Client 'null' HAS BEEN DISCONNECTED . PROBE: false; ProcessorID: 113
[2015-08-24 17:15:15] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:15:15] [SERVER] COULD NOT UNREGISTER CLIENT null
[2015-08-24 17:15:15] [SERVER] SERVER PROCESSOR STOPPED FOR null; PID 113
[2015-08-24 17:15:42] [SERVERSOCKET] Connection made. starting new processor 3859, /XXX.XXX.XXX.XXX local: 4242, /185.49.14.96, keepalive false
[2015-08-24 17:15:42] [SERVER] connection registered (TRunning: 2/2/2) 114
[2015-08-24 17:15:42] [SERVER][PROCESSOR] client setup completed for PID: 114. listening for input
[2015-08-24 17:15:42] [SERVER][LOGIN] new client connected. given id: 10: description: Telepatos
[2015-08-24 17:15:42] [SERVER][LOGIN] return code 0
[2015-08-24 17:15:44] [SERVER] client has not send any login information: 2000 / 10000 ms; ProcessorID: 114
[2015-08-24 17:15:45] [SERVER] client has not send any login information: 3000 / 10000 ms; ProcessorID: 114
[2015-08-24 17:15:46] [SERVER] client has not send any login information: 4000 / 10000 ms; ProcessorID: 114
[2015-08-24 17:15:47] [SERVER] client has not send any login information: 5000 / 10000 ms; ProcessorID: 114
[2015-08-24 17:15:48] [SERVER] client has not send any login information: 6000 / 10000 ms; ProcessorID: 114
[..............]

Details

Task Type
Bug
Testing Results
Affected Gamemode(s)
Serverside
Reproducible
Not on purpose
Last tested (version)
0.19401
Category
Engine
First occurrence (version)
0.19401
Hardware/Software/System
OS-Specific
No
Hardware-Specific
No
Video Card Vendor
uncertain
Java Version (64bit)

java version "1.8.0_60"
Java(TM) SE Runtime Environment (build 1.8.0_60-b27)
Java HotSpot(TM) 64-Bit Server VM (build 25.60-b23, mixed mode)

Event Timeline

Warwarin updated the task description. (Show Details)Aug 24 2015, 4:14 PM
Warwarin changed Affected Gamemode(s) from none/unspecified to Serverside.
Warwarin set First occurrence (version) to 0.19401.
Warwarin changed Reproducible from uncertain to Yes.
Warwarin set Last tested (version) to 0.19401.
Warwarin edited Serverconfig (server.cfg). (Show Details)
Warwarin edited Clientconfig (settings.cfg). (Show Details)
Warwarin edited Java Version (64bit). (Show Details)
Warwarin added a subscriber: Warwarin.
Warwarin created this task.
Restricted Application added a project: Issue Navigation. · View Herald TranscriptAug 24 2015, 4:14 PM
Warwarin set Task Type to Bug.Aug 24 2015, 4:34 PM
Warwarin set Category to Engine.
Warwarin changed Reproducible from Yes to Not on purpose.
Warwarin set OS-Specific to No.
Warwarin set Hardware-Specific to No.
Warwarin set Video Card Vendor to uncertain.
Warwarin renamed this task from Factory Crititcal Bug to Server Controller Exceptions.
AndyP shifted this object from the S1 Public space to the S3 Starmade space.Aug 24 2015, 4:43 PM
AndyP changed the visibility from "Custom Policy" to "Public (No Login Required)".
AndyP changed the edit policy from "Task Author" to "All Users".
AndyP updated the task description. (Show Details)
AndyP moved this task from New / Unconfirmed to Feedback on the Issue Navigation board.
AndyP claimed this task.
AndyP added a comment.Aug 24 2015, 4:46 PM

-Feedback-

As you removed all IPs and other relevant information, I guess its fine to make it public.

The very first line of the log looks like it was the original cause, may you add a few more lines before that?
(like 10-20 so we can see what the server was doing right before this locked up.)

Warwarin updated the task description. (Show Details)Aug 24 2015, 5:03 PM

I added lines from last sector spawn.

P.S. I always protect the privacy of my players ;P

AndyP added a comment.Aug 24 2015, 5:44 PM

Looking closer into this, together with schema opened the assumption there are modifications to the server being injected, or implemented before starting it.

Any change you did on purpose?
The abstract method error is usally when some uncompiled class gets into the execution, as this is more a compile time error, OR in this case, when being thrown at runtime, could be an effect of a Just-in-time compile attempt failing.

If you have an option to do so, get a fresh copy of all .jar files and compare the checksums to the ones on the server.

This comment was removed by Warwarin.
Warwarin changed the task status from Open to Resolved.Aug 25 2015, 7:20 AM
AndyP closed this task as Rejected.Aug 25 2015, 3:13 PM

-Rejected-

For terms of bug-fixing, if you find anything new or relevant, please poke me about this. =)

Restricted Application edited projects, added Starmade; removed Issue Navigation. · View Herald TranscriptAug 25 2015, 3:13 PM
AndyP removed AndyP as the assignee of this task.Sep 7 2015, 9:05 PM
Restricted Application added a project: Engine. · View Herald TranscriptMar 10 2017, 6:27 PM