2016-05-31 01:00:36.931812 [debug] Server : Logger initialized 2016-05-31 01:00:36.932009 [debug] Server : v0.4.5+ [build 2016-05-25.e888bed] CMake 2016-05-31 01:00:36.932052 [debug] Server : Dependency versions from headers: 2016-05-31 01:00:36.932061 [debug] Server : Boost: 1_55 2016-05-31 01:00:36.932072 [debug] Server : FreeType: 2.5.2 2016-05-31 01:00:36.932079 [debug] Server : PNG: 1.2.50 2016-05-31 01:00:36.932086 [debug] Server : Python: 2.7.9 2016-05-31 01:00:36.932093 [debug] Server : SDL: 2.0.2 2016-05-31 01:00:36.932099 [debug] Server : zlib: 1.2.8 2016-05-31 01:00:36.932112 [debug] Server : (ServerFSM) Idle 2016-05-31 01:00:36.932129 [debug] Server : Initializing FreeOrion Python interface 2016-05-31 01:00:36.942309 [debug] Server : Python initialized 2016-05-31 01:00:36.942367 [debug] Server : Python version: 2.7.9 (default, Mar 1 2015, 13:01:26) [GCC 4.9.2] 2016-05-31 01:00:36.942388 [debug] Server : Python prefix: /usr 2016-05-31 01:00:36.942402 [debug] Server : Python module search path: /usr/lib/python2.7/:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload 2016-05-31 01:00:36.942411 [debug] Server : Initializing C++ interfaces for Python 2016-05-31 01:00:36.942927 [debug] Server : Python stdout and stderr redirected 2016-05-31 01:00:36.942954 [debug] Server : Initializing server Python modules 2016-05-31 01:00:38.081985 [debug] Server : Server Python modules successfully initialized! 2016-05-31 01:00:38.082047 [debug] Server : FreeOrion Python interface successfully initialized! 2016-05-31 01:00:38.082058 [debug] Server : FreeOrion server waiting for network events 2016-05-31 01:00:38.082123 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-05-31 01:00:38.082155 [debug] Server : Server received message from player id: -1 of type Host SP Game and size 970 2016-05-31 01:00:38.082178 [debug] Server : (ServerFSM) Idle.HostSPGame 2016-05-31 01:00:38.120933 [debug] Server : Reading player save game data from: /home/andrew/.local/share/freeorion/save/save-20160531_005640.sav 2016-05-31 01:00:38.120991 [debug] Server : Attempting binary deserialization... 2016-05-31 01:00:38.121124 [debug] Server : Trying again with XML deserialization... 2016-05-31 01:00:38.121360 [debug] Server : Done reading player save game data... 2016-05-31 01:00:38.121381 [debug] Server : PlayerConnection(@ 0xc574b0)::EstablishPlayer(1, Human_Player, 1,v0.4.5+ [build 2016-05-25.e888bed] CMake) 2016-05-31 01:00:38.121436 [debug] Server : (ServerFSM) ~Idle 2016-05-31 01:00:38.121444 [debug] Server : (ServerFSM) WaitingForSPGameJoiners 2016-05-31 01:00:38.121479 [debug] Server : Reading player save game data from: /home/andrew/.local/share/freeorion/save/save-20160531_005640.sav 2016-05-31 01:00:38.121493 [debug] Server : Attempting binary deserialization... 2016-05-31 01:00:38.121520 [debug] Server : Trying again with XML deserialization... 2016-05-31 01:00:38.121651 [debug] Server : Done reading player save game data... 2016-05-31 01:00:38.121665 [debug] Server : ServerApp::CreateAIClients: 5 player (maybe not all AIs) at max aggression: 5 2016-05-31 01:00:38.121726 [debug] Server : ServerApp::CleanupAIs() telling AIs game is ending 2016-05-31 01:00:38.121734 [debug] Server : ServerApp::CleanupAIs() killing 0 AI clients. 2016-05-31 01:00:38.121759 [debug] Server : starting /home/andrew/Games/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-05-31 01:00:38.121766 [debug] Server : ai-path set to 'AI' 2016-05-31 01:00:38.121774 [debug] Server : ai-config not set. 2016-05-31 01:00:39.122484 [debug] Server : done starting /home/andrew/Games/freeorion/freeorionca 2016-05-31 01:00:39.122634 [debug] Server : starting /home/andrew/Games/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-05-31 01:00:39.122653 [debug] Server : ai-path set to 'AI' 2016-05-31 01:00:39.122668 [debug] Server : ai-config not set. 2016-05-31 01:00:40.123756 [debug] Server : done starting /home/andrew/Games/freeorion/freeorionca 2016-05-31 01:00:40.123845 [debug] Server : starting /home/andrew/Games/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-05-31 01:00:40.123868 [debug] Server : ai-path set to 'AI' 2016-05-31 01:00:40.123877 [debug] Server : ai-config not set. 2016-05-31 01:00:41.124540 [debug] Server : done starting /home/andrew/Games/freeorion/freeorionca 2016-05-31 01:00:41.124648 [debug] Server : starting /home/andrew/Games/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-05-31 01:00:41.124658 [debug] Server : ai-path set to 'AI' 2016-05-31 01:00:41.124666 [debug] Server : ai-config not set. 2016-05-31 01:00:42.125396 [debug] Server : done starting /home/andrew/Games/freeorion/freeorionca 2016-05-31 01:00:42.125482 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-05-31 01:00:42.125515 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-05-31 01:00:42.125553 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-05-31 01:00:42.125574 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-05-31 01:00:42.125594 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-05-31 01:00:42.125604 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-05-31 01:00:42.125709 [debug] Server : PlayerConnection(@ 0xd88a90)::EstablishPlayer(2, AI_3, 0,v0.4.5+ [build 2016-05-25.e888bed] CMake) 2016-05-31 01:00:42.125763 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-05-31 01:00:42.125780 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-05-31 01:00:42.125794 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-05-31 01:00:42.125805 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-05-31 01:00:42.125813 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-05-31 01:00:42.125866 [debug] Server : PlayerConnection(@ 0x14df270)::EstablishPlayer(3, AI_4, 0,v0.4.5+ [build 2016-05-25.e888bed] CMake) 2016-05-31 01:00:42.125890 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-05-31 01:00:42.125902 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-05-31 01:00:42.125913 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-05-31 01:00:42.125921 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-05-31 01:00:42.125970 [debug] Server : PlayerConnection(@ 0x1427380)::EstablishPlayer(4, AI_2, 0,v0.4.5+ [build 2016-05-25.e888bed] CMake) 2016-05-31 01:00:42.125994 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-05-31 01:00:42.220854 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-05-31 01:00:42.221366 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-05-31 01:00:42.221390 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-05-31 01:00:42.221399 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-05-31 01:00:42.221471 [debug] Server : PlayerConnection(@ 0x14fabc0)::EstablishPlayer(5, AI_1, 0,v0.4.5+ [build 2016-05-25.e888bed] CMake) 2016-05-31 01:00:42.221500 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-05-31 01:00:42.221509 [debug] Server : WaitingForSPGameJoiners::react(const CheckStartConditions& u) : have all 5 expected players connected. 2016-05-31 01:00:42.221518 [debug] Server : Loading SP game save file: /home/andrew/.local/share/freeorion/save/save-20160531_005640.sav 2016-05-31 01:00:42.221716 [debug] Server : Allocating buffers for XML deserialization... 2016-05-31 01:00:44.428599 [debug] Server : Universe::serialize : deserializing universe width: 1008.9 2016-05-31 01:00:44.924182 [debug] Server : Universe::serialize : deserializing 0 ship designs 2016-05-31 01:00:45.611923 [debug] Server : Universe::serialize : deserializing empire object visibility for 5, 5, 5, 5 empires 2016-05-31 01:00:45.755117 [debug] Server : Universe::serialize : deserializing 1071 objects 2016-05-31 01:00:45.769350 [debug] Server : Universe::serialize : deserializing 12965 destroyed object ids 2016-05-31 01:00:49.235709 [debug] Server : Universe::serialize : deserializing empire known objects for 5 empires 2016-05-31 01:00:49.235760 [debug] Server : Universe::serialize : deserializing last allocated ids 2016-05-31 01:00:49.350883 [debug] Server : Universe::serialize : deserializing 18 types of statistic 2016-05-31 01:00:49.350925 [debug] Server : Universe::serialize : deserializing done 2016-05-31 01:00:49.350932 [debug] Server : Universe::serialize : updating empires' latest known object destruction states 2016-05-31 01:00:49.355982 [debug] Server : Universe::serialize done 2016-05-31 01:00:49.375184 [debug] Server : LoadGame : Successfully loaded save file 2016-05-31 01:00:49.375664 [debug] Server : LoadGame: /home/andrew/.local/share/freeorion/save/save-20160531_005640.sav time: 7085.11 2016-05-31 01:00:49.376480 [debug] Server : ServerApp::LoadGameInit 2016-05-31 01:00:49.403079 [debug] Server : ========= Production Update for empire: 1 ======== 2016-05-31 01:00:49.407497 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:00:49.413151 [debug] Server : ProductionQueue::Update: Projections took 330 microseconds with 449.111 total Production Points 2016-05-31 01:00:49.431461 [debug] Server : ========= Production Update for empire: 2 ======== 2016-05-31 01:00:50.131386 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:00:50.131846 [debug] Server : ProductionQueue::Update: Projections took 105 microseconds with 1457.87 total Production Points 2016-05-31 01:00:50.177174 [debug] Server : ========= Production Update for empire: 3 ======== 2016-05-31 01:00:50.180621 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:00:50.183664 [debug] Server : ProductionQueue::Update: Projections took 308 microseconds with 384.425 total Production Points 2016-05-31 01:00:50.191541 [debug] Server : ========= Production Update for empire: 4 ======== 2016-05-31 01:00:50.192951 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:00:50.194379 [debug] Server : ProductionQueue::Update: Projections took 106 microseconds with 5 total Production Points 2016-05-31 01:00:50.196921 [debug] Server : ========= Production Update for empire: 5 ======== 2016-05-31 01:00:50.201754 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:00:50.207169 [debug] Server : ProductionQueue::Update: Projections took 5351 microseconds with 1913.52 total Production Points 2016-05-31 01:00:50.209025 [debug] Server : ServerApp::GetPlayerInfoMap: Compiling PlayerInfo for each player 2016-05-31 01:00:50.209058 [debug] Server : ServerApp::CommonGameInit: Sending GameStartMessages to players 2016-05-31 01:00:50.275139 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:00:50.284189 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:00:50.287688 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:00:50.288112 [debug] Server : Universe::serialize : serializing 140 ship designs 2016-05-31 01:00:50.293238 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:00:50.335960 [debug] Server : Universe::serialize : serializing 6867 objects 2016-05-31 01:00:50.336348 [debug] Server : Universe::serialize : serializing 6110 destroyed object ids 2016-05-31 01:00:50.336373 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:00:50.336401 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:00:50.341642 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:00:50.341690 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:00:50.341698 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:00:50.342300 [debug] Server : Universe::serialize done 2016-05-31 01:00:50.414457 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:00:50.415260 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:00:50.415879 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:00:50.416246 [debug] Server : Universe::serialize : serializing 154 ship designs 2016-05-31 01:00:50.416806 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:00:50.422443 [debug] Server : Universe::serialize : serializing 604 objects 2016-05-31 01:00:50.422506 [debug] Server : Universe::serialize : serializing 346 destroyed object ids 2016-05-31 01:00:50.422517 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:00:50.422525 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:00:50.427364 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:00:50.427409 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:00:50.427417 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:00:50.427500 [debug] Server : Universe::serialize done 2016-05-31 01:00:50.515428 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:00:50.530724 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:00:50.535500 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:00:50.536397 [debug] Server : Universe::serialize : serializing 381 ship designs 2016-05-31 01:00:50.545955 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:00:50.618366 [debug] Server : Universe::serialize : serializing 12308 objects 2016-05-31 01:00:50.618883 [debug] Server : Universe::serialize : serializing 11530 destroyed object ids 2016-05-31 01:00:50.618898 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:00:50.618906 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:00:50.623746 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:00:50.623771 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:00:50.623778 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:00:50.625070 [debug] Server : Universe::serialize done 2016-05-31 01:00:50.736052 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:00:50.741180 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:00:50.743038 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:00:50.744055 [debug] Server : Universe::serialize : serializing 345 ship designs 2016-05-31 01:00:50.747622 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:00:50.779235 [debug] Server : Universe::serialize : serializing 3916 objects 2016-05-31 01:00:50.779418 [debug] Server : Universe::serialize : serializing 3124 destroyed object ids 2016-05-31 01:00:50.779430 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:00:50.779438 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:00:50.784736 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:00:50.784780 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:00:50.784789 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:00:50.785340 [debug] Server : Universe::serialize done 2016-05-31 01:00:50.880386 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:00:50.894729 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:00:50.899816 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:00:50.900569 [debug] Server : Universe::serialize : serializing 278 ship designs 2016-05-31 01:00:50.912387 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:00:50.986247 [debug] Server : Universe::serialize : serializing 11412 objects 2016-05-31 01:00:50.986743 [debug] Server : Universe::serialize : serializing 10322 destroyed object ids 2016-05-31 01:00:50.986761 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:00:50.986770 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:00:50.992012 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:00:50.992032 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:00:50.992039 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:00:50.993428 [debug] Server : Universe::serialize done 2016-05-31 01:00:51.009081 [debug] Server : (ServerFSM) ~WaitingForSPGameJoiners 2016-05-31 01:00:51.009231 [debug] Server : (ServerFSM) PlayingGame 2016-05-31 01:00:51.009241 [debug] Server : (ServerFSM) WaitingForTurnEnd 2016-05-31 01:00:51.009263 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-05-31 01:00:55.199512 [debug] Server : Server received message from player id: 2 of type Turn Orders and size 3569 2016-05-31 01:00:55.199554 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:00:55.200218 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 2 2016-05-31 01:00:55.200374 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:00:55.200383 [debug] Server : ServerApp::AllOrdersReceived for turn: 295 2016-05-31 01:00:55.200391 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:00:55.200398 [debug] Server : ... no orders from empire id: 2 2016-05-31 01:00:55.200407 [debug] Server : ... no orders from empire id: 3 2016-05-31 01:00:55.200414 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:00:55.200421 [debug] Server : ... no orders from empire id: 5 2016-05-31 01:01:01.278502 [debug] Server : Server received message from player id: 3 of type Turn Orders and size 16906 2016-05-31 01:01:01.278543 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:01.279759 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 3 2016-05-31 01:01:01.279926 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:01.279937 [debug] Server : ServerApp::AllOrdersReceived for turn: 295 2016-05-31 01:01:01.279946 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:01.279954 [debug] Server : ... no orders from empire id: 2 2016-05-31 01:01:01.279962 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:01.279970 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:01.279978 [debug] Server : ... no orders from empire id: 5 2016-05-31 01:01:01.757342 [debug] Server : Server received message from player id: 5 of type Player Chat and size 122 2016-05-31 01:01:01.757389 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:02.321370 [debug] Server : Server received message from player id: 4 of type Player Chat and size 122 2016-05-31 01:01:02.321421 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:02.892286 [debug] Server : Server received message from player id: 5 of type Turn Orders and size 73452 2016-05-31 01:01:02.892330 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:02.897626 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 5 2016-05-31 01:01:02.897871 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:02.897896 [debug] Server : ServerApp::AllOrdersReceived for turn: 295 2016-05-31 01:01:02.897906 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:02.897921 [debug] Server : ... no orders from empire id: 2 2016-05-31 01:01:02.897929 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:02.897938 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:02.897945 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:03.501947 [debug] Server : Server received message from player id: 4 of type Turn Orders and size 43887 2016-05-31 01:01:03.501992 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:03.504820 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 4 2016-05-31 01:01:03.504964 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:03.504973 [debug] Server : ServerApp::AllOrdersReceived for turn: 295 2016-05-31 01:01:03.504980 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:03.504987 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:03.504995 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:03.505001 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:03.505008 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:16.478459 [debug] Server : Server received message from player id: 1 of type Turn Orders and size 1616 2016-05-31 01:01:16.478508 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:16.478690 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 1 2016-05-31 01:01:16.478847 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:16.478857 [debug] Server : ServerApp::AllOrdersReceived for turn: 295 2016-05-31 01:01:16.478865 [debug] Server : ... have orders from empire id: 1 2016-05-31 01:01:16.478872 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:16.478879 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:16.478886 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:16.478894 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:16.478901 [debug] Server : WaitingForTurnEnd.TurnOrders : All orders received. 2016-05-31 01:01:16.478908 [debug] Server : (ServerFSM) ~WaitingForTurnEndIdle 2016-05-31 01:01:16.478915 [debug] Server : (ServerFSM) ~WaitingForTurnEnd 2016-05-31 01:01:16.478922 [debug] Server : (ServerFSM) ProcessingTurn 2016-05-31 01:01:16.478930 [debug] Server : (ServerFSM) ProcessingTurn.ProcessTurn 2016-05-31 01:01:16.478948 [debug] Server : ServerApp::ProcessTurns executing orders 2016-05-31 01:01:16.479230 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 13908 to 297 147 294 310 207 152 2016-05-31 01:01:16.479255 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 9897 to 66 319 2016-05-31 01:01:16.705048 [debug] Server : Inserting object with id 14161 2016-05-31 01:01:16.705134 [debug] Server : Inserting object with id 14162 2016-05-31 01:01:16.705167 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 11095 to 266 250 2016-05-31 01:01:16.804196 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 14162 to 250 301 2016-05-31 01:01:16.904542 [debug] Server : ========= Production Update for empire: 1 ======== 2016-05-31 01:01:16.907850 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:16.911496 [debug] Server : ProductionQueue::Update: Projections took 120 microseconds with 449.111 total Production Points 2016-05-31 01:01:16.911543 [debug] Server : ========= Production Update for empire: 2 ======== 2016-05-31 01:01:16.912448 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:16.912763 [debug] Server : ProductionQueue::Update: Projections took 68 microseconds with 1457.87 total Production Points 2016-05-31 01:01:16.912801 [debug] Server : ========= Production Update for empire: 3 ======== 2016-05-31 01:01:16.914643 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:16.916633 [debug] Server : ProductionQueue::Update: Projections took 234 microseconds with 384.425 total Production Points 2016-05-31 01:01:16.916693 [debug] Server : ========= Production Update for empire: 4 ======== 2016-05-31 01:01:16.917435 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:16.918210 [debug] Server : ProductionQueue::Update: Projections took 60 microseconds with 5 total Production Points 2016-05-31 01:01:16.918227 [debug] Server : ========= Production Update for empire: 5 ======== 2016-05-31 01:01:16.922521 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:16.925688 [debug] Server : ProductionQueue::Update: Projections took 3133 microseconds with 1913.52 total Production Points 2016-05-31 01:01:16.925821 [debug] Server : ServerApp::ProcessTurns colonization 2016-05-31 01:01:16.925945 [debug] Server : ServerApp::ProcessTurns invasion 2016-05-31 01:01:16.926471 [debug] Server : ServerApp::ProcessTurns gifting 2016-05-31 01:01:16.926595 [debug] Server : ServerApp::ProcessTurns scrapping 2016-05-31 01:01:16.926751 [debug] Server : ServerApp::ProcessTurns movement 2016-05-31 01:01:17.004828 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:17.015325 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:17.018150 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:17.018380 [debug] Server : Universe::serialize : serializing 140 ship designs 2016-05-31 01:01:17.022482 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:17.054788 [debug] Server : Universe::serialize : serializing 6867 objects 2016-05-31 01:01:17.055500 [debug] Server : Universe::serialize : serializing 6110 destroyed object ids 2016-05-31 01:01:17.055517 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:17.055525 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:17.060077 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:17.060127 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:17.060136 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:17.061167 [debug] Server : Universe::serialize done 2016-05-31 01:01:17.070199 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:17.071222 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:17.072035 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:17.072306 [debug] Server : Universe::serialize : serializing 154 ship designs 2016-05-31 01:01:17.072710 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:17.077466 [debug] Server : Universe::serialize : serializing 604 objects 2016-05-31 01:01:17.077545 [debug] Server : Universe::serialize : serializing 346 destroyed object ids 2016-05-31 01:01:17.077558 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:17.077568 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:17.082427 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:17.082456 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:17.082464 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:17.082572 [debug] Server : Universe::serialize done 2016-05-31 01:01:17.083616 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:17.103890 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:17.108899 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:17.109638 [debug] Server : Universe::serialize : serializing 382 ship designs 2016-05-31 01:01:17.118700 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:17.173809 [debug] Server : Universe::serialize : serializing 12309 objects 2016-05-31 01:01:17.174847 [debug] Server : Universe::serialize : serializing 11531 destroyed object ids 2016-05-31 01:01:17.174858 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:17.174865 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:17.179105 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:17.179127 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:17.179135 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:17.181192 [debug] Server : Universe::serialize done 2016-05-31 01:01:17.200019 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:17.207100 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:17.209293 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:17.209823 [debug] Server : Universe::serialize : serializing 347 ship designs 2016-05-31 01:01:17.212835 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:17.236848 [debug] Server : Universe::serialize : serializing 3917 objects 2016-05-31 01:01:17.237314 [debug] Server : Universe::serialize : serializing 3126 destroyed object ids 2016-05-31 01:01:17.237332 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:17.237341 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:17.242543 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:17.242585 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:17.242594 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:17.243328 [debug] Server : Universe::serialize done 2016-05-31 01:01:17.248892 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:17.269011 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:17.273975 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:17.274527 [debug] Server : Universe::serialize : serializing 278 ship designs 2016-05-31 01:01:17.284297 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:17.339565 [debug] Server : Universe::serialize : serializing 11415 objects 2016-05-31 01:01:17.340711 [debug] Server : Universe::serialize : serializing 10322 destroyed object ids 2016-05-31 01:01:17.340730 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:17.340739 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:17.345920 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:17.345954 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:17.345962 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:17.347943 [debug] Server : Universe::serialize done 2016-05-31 01:01:17.363200 [debug] Server : ServerApp::PreCombatProcessTurns time: 858.01 2016-05-31 01:01:17.363262 [debug] Server : ServerApp::ProcessCombats 2016-05-31 01:01:17.364507 [debug] Server : AutoResolveCombat at Al Borak 2016-05-31 01:01:17.364748 [debug] Server : AutoResolveCombat at Kintana 2016-05-31 01:01:17.365011 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Fermi shield: 0 structure: 7 2016-05-31 01:01:17.365045 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Chandrasekhar shield: 0 structure: 7 2016-05-31 01:01:17.365084 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Kagan II shield: 0 structure: 7 2016-05-31 01:01:17.365111 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Hastaa II shield: 0 structure: 7 2016-05-31 01:01:17.365136 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Fermi shield: 0 structure: -28 2016-05-31 01:01:17.365160 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Vonnegut III shield: 0 structure: 7 2016-05-31 01:01:17.365185 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Flint shield: 0 structure: 7 2016-05-31 01:01:17.365209 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Tarf shield: 0 structure: 7 2016-05-31 01:01:17.365235 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Hickory shield: 0 structure: 7 2016-05-31 01:01:17.365279 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Scheat shield: 0 structure: 7 2016-05-31 01:01:17.365306 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Komatite II shield: 0 structure: 7 2016-05-31 01:01:17.365331 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Kiwi II shield: 0 structure: 7 2016-05-31 01:01:17.365354 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Chandrasekhar shield: 0 structure: -28 2016-05-31 01:01:17.365379 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Chandrasekhar shield: 0 structure: -63 2016-05-31 01:01:17.365404 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Kagan II shield: 0 structure: -28 2016-05-31 01:01:17.365428 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Chandrasekhar shield: 0 structure: -98 2016-05-31 01:01:17.365452 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Krittika II shield: 0 structure: -48 2016-05-31 01:01:17.365476 [debug] Server : AttackShipShip: attacker: Uttarashada damage: 35 target: Fermi shield: 0 structure: -63 2016-05-31 01:01:17.365510 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Kiwi II shield: 0 structure: -28 2016-05-31 01:01:17.365535 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Kandinsky shield: 0 structure: 7 2016-05-31 01:01:17.365559 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Pisces shield: 0 structure: 7 2016-05-31 01:01:17.365583 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hickory shield: 0 structure: -28 2016-05-31 01:01:17.365607 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Fermi shield: 0 structure: -98 2016-05-31 01:01:17.365630 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Humor shield: 0 structure: 7 2016-05-31 01:01:17.365669 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Fermi shield: 0 structure: -133 2016-05-31 01:01:17.365694 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Budhavar shield: 0 structure: 7 2016-05-31 01:01:17.365975 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hastaa shield: 0 structure: 7 2016-05-31 01:01:17.365997 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Alruccabah shield: 0 structure: -48 2016-05-31 01:01:17.366016 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hastaa shield: 0 structure: -28 2016-05-31 01:01:17.366035 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Maasim shield: 0 structure: 7 2016-05-31 01:01:17.366053 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hart III shield: 0 structure: 7 2016-05-31 01:01:17.366072 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Lacerta II shield: 0 structure: 7 2016-05-31 01:01:17.366091 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hastaa shield: 0 structure: -63 2016-05-31 01:01:17.366115 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Ambivalance II shield: 0 structure: 7 2016-05-31 01:01:17.366136 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Maasim shield: 0 structure: -28 2016-05-31 01:01:17.366174 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Lacerta II shield: 0 structure: -28 2016-05-31 01:01:17.366193 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Alruccabah shield: 0 structure: -83 2016-05-31 01:01:17.366212 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Maasim shield: 0 structure: -63 2016-05-31 01:01:17.366231 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Ellison II shield: 0 structure: 7 2016-05-31 01:01:17.366250 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Lacerta II shield: 0 structure: -63 2016-05-31 01:01:17.366274 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Alruccabah shield: 0 structure: -118 2016-05-31 01:01:17.366294 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Kaou shield: 0 structure: 7 2016-05-31 01:01:17.366312 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Microscopium shield: 0 structure: 7 2016-05-31 01:01:17.366456 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Banks shield: 0 structure: 7 2016-05-31 01:01:17.366474 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Hooke shield: 0 structure: -48 2016-05-31 01:01:17.366489 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Barracuda shield: 0 structure: 7 2016-05-31 01:01:17.366506 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Angetenar shield: 0 structure: 7 2016-05-31 01:01:17.366527 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Hooke shield: 0 structure: -83 2016-05-31 01:01:17.366542 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Bass shield: 0 structure: 7 2016-05-31 01:01:17.366558 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Banks shield: 0 structure: -28 2016-05-31 01:01:17.366574 [debug] Server : AttackShipShip: attacker: Hyrax damage: 35 target: Bass shield: 0 structure: -28 2016-05-31 01:01:17.366600 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Bass shield: 0 structure: -63 2016-05-31 01:01:17.366616 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Angetenar shield: 0 structure: -28 2016-05-31 01:01:17.366632 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Angetenar shield: 0 structure: -63 2016-05-31 01:01:17.366647 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Banks shield: 0 structure: -63 2016-05-31 01:01:17.366663 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Angetenar shield: 0 structure: -98 2016-05-31 01:01:17.366679 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Bass shield: 0 structure: -98 2016-05-31 01:01:17.366695 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Angetenar shield: 0 structure: -133 2016-05-31 01:01:17.366710 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Bass shield: 0 structure: -133 2016-05-31 01:01:17.366726 [debug] Server : AttackShipShip: attacker: Dhanu II damage: 35 target: Hooke shield: 0 structure: -118 2016-05-31 01:01:17.367051 [debug] Server : AutoResolveCombat at Cassandra 2016-05-31 01:01:17.367117 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Nath shield: 0 structure: 21 2016-05-31 01:01:17.367134 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Nath shield: 0 structure: -24 2016-05-31 01:01:17.367151 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Acubens shield: 0 structure: 21 2016-05-31 01:01:17.367166 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Acubens shield: 0 structure: -24 2016-05-31 01:01:17.367187 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Acubens shield: 0 structure: -69 2016-05-31 01:01:17.367203 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Nath shield: 0 structure: -69 2016-05-31 01:01:17.367218 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Nath shield: 0 structure: -114 2016-05-31 01:01:17.367233 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Tsunami II shield: 0 structure: 21 2016-05-31 01:01:17.367289 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: 21 2016-05-31 01:01:17.367305 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: -24 2016-05-31 01:01:17.367320 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: -69 2016-05-31 01:01:17.367334 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: -114 2016-05-31 01:01:17.367349 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Phad shield: 0 structure: 21 2016-05-31 01:01:17.367363 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: -159 2016-05-31 01:01:17.367378 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Phad shield: 0 structure: -24 2016-05-31 01:01:17.367392 [debug] Server : AttackShipShip: attacker: Graffias damage: 45 target: Chertan II shield: 0 structure: -204 2016-05-31 01:01:17.392803 [debug] Server : ServerApp::ProcessCombats time: 29.435 2016-05-31 01:01:17.423864 [debug] Server : ServerApp::PostCombatProcessTurns effects and meter updates 2016-05-31 01:01:17.816403 [debug] Server : Issue times: planet species: 0.306 ship species: 0.344 specials: 1.401 techs: 9.447 buildings: 0.765 hulls/parts: 3.657 fields: 11.045 2016-05-31 01:01:17.816464 [debug] Server : Evaluation time: 722.169 reorder time: 25.877 2016-05-31 01:01:17.873038 [debug] Server : Universe::ExecuteEffects time: 50.029 2016-05-31 01:01:17.878203 [debug] Server : ServerApp::PostCombatProcessTurns empire resources updates 2016-05-31 01:01:17.896929 [debug] Server : ========= Production Update for empire: 1 ======== 2016-05-31 01:01:17.901047 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:17.904934 [debug] Server : ProductionQueue::Update: Projections took 160 microseconds with 449.111 total Production Points 2016-05-31 01:01:17.915630 [debug] Server : ========= Production Update for empire: 2 ======== 2016-05-31 01:01:17.917017 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:17.917414 [debug] Server : ProductionQueue::Update: Projections took 84 microseconds with 1463.87 total Production Points 2016-05-31 01:01:17.958757 [debug] Server : ========= Production Update for empire: 3 ======== 2016-05-31 01:01:17.961682 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:17.964963 [debug] Server : ProductionQueue::Update: Projections took 331 microseconds with 397.425 total Production Points 2016-05-31 01:01:17.977601 [debug] Server : ========= Production Update for empire: 4 ======== 2016-05-31 01:01:17.978708 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:17.979651 [debug] Server : ProductionQueue::Update: Projections took 79 microseconds with 5 total Production Points 2016-05-31 01:01:17.984273 [debug] Server : ========= Production Update for empire: 5 ======== 2016-05-31 01:01:17.989747 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:17.993516 [debug] Server : ProductionQueue::Update: Projections took 3705 microseconds with 1915.52 total Production Points 2016-05-31 01:01:17.998221 [debug] Server : ServerApp::PostCombatProcessTurns queue progress checking 2016-05-31 01:01:17.998323 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:18.000163 [debug] Server : Completed an item: Battleship I 2016-05-31 01:01:18.000799 [debug] Server : New Ship, id 14163, created on turn: 295 2016-05-31 01:01:18.002925 [debug] Server : New Fleet "Fleet 14164" created on turn: 295 2016-05-31 01:01:18.002982 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:18.003041 [debug] Server : Location test failed for BuildingType BLD_COL_EXOBOT at location Sssla α I 2016-05-31 01:01:18.003355 [debug] Server : Location test failed for Ships(s) with design id 540 at location Kintana I 2016-05-31 01:01:18.004285 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:18.005241 [debug] Server : Location test failed for Ships(s) with design id 438 at location Gion I 2016-05-31 01:01:18.005284 [debug] Server : Location test failed for Ships(s) with design id 65 at location Oasis β II 2016-05-31 01:01:18.005370 [debug] Server : Location test failed for Ships(s) with design id 593 at location Gion I 2016-05-31 01:01:18.005453 [debug] Server : Location test failed for Ships(s) with design id 593 at location Gion I 2016-05-31 01:01:18.005688 [debug] Server : Completed an item: AY Manticore Mk. 5 2016-05-31 01:01:18.005729 [debug] Server : New Ship, id 14165, created on turn: 295 2016-05-31 01:01:18.005738 [debug] Server : Marking completed production queue item to be removed form queue 2016-05-31 01:01:18.006014 [debug] Server : Completed an item: AY Hades Mk. 2 2016-05-31 01:01:18.006061 [debug] Server : New Ship, id 14166, created on turn: 295 2016-05-31 01:01:18.006073 [debug] Server : Marking completed production queue item to be removed form queue 2016-05-31 01:01:18.006739 [debug] Server : New Fleet "Fleet 14167" created on turn: 295 2016-05-31 01:01:18.006772 [debug] Server : New Fleet "Fleet 14168" created on turn: 295 2016-05-31 01:01:18.008458 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:18.008619 [debug] Server : Location test failed for Ships(s) with design id 43 at location Reul α II 2016-05-31 01:01:18.008708 [debug] Server : Location test failed for Ships(s) with design id 213 at location Royal Girtab α I 2016-05-31 01:01:18.008793 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:18.008877 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:18.008961 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:18.009044 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:18.009290 [debug] Server : Location test failed for Ships(s) with design id 214 at location Makina α II 2016-05-31 01:01:18.009617 [debug] Server : Location test failed for Ships(s) with design id 246 at location Makina α II 2016-05-31 01:01:18.009773 [debug] Server : Location test failed for Ships(s) with design id 214 at location Makina α Asteroids 2016-05-31 01:01:18.010097 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:18.015326 [debug] Server : Location test failed for BuildingType BLD_COL_UGMORS at location Reul β I 2016-05-31 01:01:18.015683 [debug] Server : Python current directory set to /home/andrew/Games/freeorion/default/python/turn_events 2016-05-31 01:01:18.015738 [debug] Server : Executing turn events for turn 295 2016-05-31 01:01:18.361356 [debug] Server : Issue times: planet species: 0.412 ship species: 0.338 specials: 0.883 techs: 5.552 buildings: 0.664 hulls/parts: 1.326 fields: 0.035 2016-05-31 01:01:18.361419 [debug] Server : Evaluation time: 723.086 reorder time: 29.617 2016-05-31 01:01:18.422744 [debug] Server : Universe::ExecuteEffects time: 51.055 2016-05-31 01:01:18.528880 [debug] Server : ServerApp::PostCombatProcessTurns Turn number incremented to 296 2016-05-31 01:01:18.580678 [debug] Server : ServerApp::PostCombatProcessTurns Sending turn updates to players 2016-05-31 01:01:18.643378 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:18.655400 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:18.658606 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:18.658954 [debug] Server : Universe::serialize : serializing 141 ship designs 2016-05-31 01:01:18.664731 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:18.705335 [debug] Server : Universe::serialize : serializing 6873 objects 2016-05-31 01:01:18.705724 [debug] Server : Universe::serialize : serializing 6116 destroyed object ids 2016-05-31 01:01:18.705735 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:18.705743 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:18.711026 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:18.711073 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:18.711081 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:18.712104 [debug] Server : Universe::serialize done 2016-05-31 01:01:18.798360 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:18.799359 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:18.800105 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:18.800462 [debug] Server : Universe::serialize : serializing 154 ship designs 2016-05-31 01:01:18.800953 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:18.806520 [debug] Server : Universe::serialize : serializing 604 objects 2016-05-31 01:01:18.806589 [debug] Server : Universe::serialize : serializing 348 destroyed object ids 2016-05-31 01:01:18.806601 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:18.806609 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:18.811305 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:18.811336 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:18.811344 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:18.811450 [debug] Server : Universe::serialize done 2016-05-31 01:01:18.915930 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:18.935080 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:18.940621 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:18.941473 [debug] Server : Universe::serialize : serializing 383 ship designs 2016-05-31 01:01:18.951169 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:19.025981 [debug] Server : Universe::serialize : serializing 12315 objects 2016-05-31 01:01:19.026749 [debug] Server : Universe::serialize : serializing 11571 destroyed object ids 2016-05-31 01:01:19.026762 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:19.026772 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:19.046352 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:19.046394 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:19.046402 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:19.048105 [debug] Server : Universe::serialize done 2016-05-31 01:01:19.182439 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:19.198618 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:19.201026 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:19.201924 [debug] Server : Universe::serialize : serializing 348 ship designs 2016-05-31 01:01:19.215311 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:19.245074 [debug] Server : Universe::serialize : serializing 3921 objects 2016-05-31 01:01:19.245321 [debug] Server : Universe::serialize : serializing 3162 destroyed object ids 2016-05-31 01:01:19.245334 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:19.245342 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:19.250162 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:19.250191 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:19.250200 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:19.250911 [debug] Server : Universe::serialize done 2016-05-31 01:01:19.353630 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:19.369224 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:19.374404 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:19.375145 [debug] Server : Universe::serialize : serializing 278 ship designs 2016-05-31 01:01:19.387497 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:19.466473 [debug] Server : Universe::serialize : serializing 11419 objects 2016-05-31 01:01:19.467103 [debug] Server : Universe::serialize : serializing 10361 destroyed object ids 2016-05-31 01:01:19.467117 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:19.467126 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:19.471799 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:19.471829 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:19.471837 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:19.473331 [debug] Server : Universe::serialize done 2016-05-31 01:01:19.496928 [debug] Server : ServerApp::PostCombatProcessTurns done 2016-05-31 01:01:19.496977 [debug] Server : ServerApp::PostCombatProcessTurns time: 2785.17 2016-05-31 01:01:19.497542 [debug] Server : (ServerFSM) ~ProcessingTurn 2016-05-31 01:01:19.497559 [debug] Server : (ServerFSM) WaitingForTurnEnd 2016-05-31 01:01:19.497570 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-05-31 01:01:19.497591 [debug] Server : Server received message from player id: 1 of type Save Game and size 100 2016-05-31 01:01:19.497608 [debug] Server : (ServerFSM) WaitingForTurnEndIdle.SaveGameRequest 2016-05-31 01:01:19.497619 [debug] Server : (ServerFSM) ~WaitingForTurnEndIdle 2016-05-31 01:01:19.497627 [debug] Server : (ServerFSM) WaitingForSaveData 2016-05-31 01:01:19.975853 [debug] Server : Server received message from player id: 2 of type Turn Orders and size 3693 2016-05-31 01:01:20.052362 [debug] Server : Server received message from player id: 2 of type Client Save Data and size 67461 2016-05-31 01:01:20.052417 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:20.052506 [debug] Server : deserializing orders 2016-05-31 01:01:20.052539 [debug] Server : checking for ui data 2016-05-31 01:01:20.052552 [debug] Server : checking for save state string 2016-05-31 01:01:20.052563 [debug] Server : deserializing save state string 2016-05-31 01:01:20.054331 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:20.363527 [debug] Server : Server received message from player id: 1 of type Client Save Data and size 727 2016-05-31 01:01:20.363583 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:20.363651 [debug] Server : deserializing orders 2016-05-31 01:01:20.363684 [debug] Server : checking for ui data 2016-05-31 01:01:20.363697 [debug] Server : deserializing UI data 2016-05-31 01:01:20.363730 [debug] Server : checking for save state string 2016-05-31 01:01:20.363769 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:24.033332 [debug] Server : Server received message from player id: 5 of type Player Chat and size 122 2016-05-31 01:01:24.514692 [debug] Server : Server received message from player id: 1 of type Turn Orders and size 372 2016-05-31 01:01:24.976752 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-05-31 01:01:25.207847 [debug] Server : Server received message from player id: 4 of type Player Chat and size 122 2016-05-31 01:01:25.610161 [debug] Server : Server received message from player id: 5 of type Turn Orders and size 30291 2016-05-31 01:01:25.783317 [debug] Server : Server received message from player id: 5 of type Client Save Data and size 247259 2016-05-31 01:01:25.783395 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:25.783618 [debug] Server : deserializing orders 2016-05-31 01:01:25.783671 [debug] Server : checking for ui data 2016-05-31 01:01:25.783692 [debug] Server : checking for save state string 2016-05-31 01:01:25.783709 [debug] Server : deserializing save state string 2016-05-31 01:01:25.789581 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:26.957500 [debug] Server : Server received message from player id: 4 of type Turn Orders and size 22669 2016-05-31 01:01:27.021558 [debug] Server : Server received message from player id: 3 of type Turn Orders and size 16875 2016-05-31 01:01:27.206619 [debug] Server : Server received message from player id: 3 of type Client Save Data and size 249788 2016-05-31 01:01:27.206684 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:27.206844 [debug] Server : deserializing orders 2016-05-31 01:01:27.206885 [debug] Server : checking for ui data 2016-05-31 01:01:27.206947 [debug] Server : checking for save state string 2016-05-31 01:01:27.206960 [debug] Server : deserializing save state string 2016-05-31 01:01:27.212905 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:27.245543 [debug] Server : Server received message from player id: 4 of type Client Save Data and size 321859 2016-05-31 01:01:27.245606 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:27.245845 [debug] Server : deserializing orders 2016-05-31 01:01:27.245881 [debug] Server : checking for ui data 2016-05-31 01:01:27.245893 [debug] Server : checking for save state string 2016-05-31 01:01:27.245903 [debug] Server : deserializing save state string 2016-05-31 01:01:27.258767 [debug] Server : SaveGame(zlib-xml) filename: /home/andrew/.local/share/freeorion/save/auto/FreeOrion_Human_Player_Terran_0296_20160531_010118.sav 2016-05-31 01:01:27.258816 [debug] Server : Compiling save empire and preview data 2016-05-31 01:01:27.258977 [debug] Server : Allocating buffers for XML serialization... 2016-05-31 01:01:28.181219 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:28.182776 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:28.216730 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:28.235571 [debug] Server : Universe::serialize : serializing 715 ship designs 2016-05-31 01:01:28.684164 [debug] Server : Universe::serialize : serializing empire object visibility for 5, 5, 5, 5 empires 2016-05-31 01:01:28.813082 [debug] Server : Universe::serialize : serializing 1035 objects 2016-05-31 01:01:28.821721 [debug] Server : Universe::serialize : serializing 13009 destroyed object ids 2016-05-31 01:01:31.150051 [debug] Server : Universe::serialize : serializing empire known objects for 5 empires 2016-05-31 01:01:31.150113 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:31.232360 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:31.232405 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:31.232412 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:31.237011 [debug] Server : Universe::serialize done 2016-05-31 01:01:32.526897 [debug] Server : SaveGame : Successfully wrote save file 2016-05-31 01:01:32.526951 [debug] Server : SaveGame: /home/andrew/.local/share/freeorion/save/auto/FreeOrion_Human_Player_Terran_0296_20160531_010118.sav time: 4953.82 2016-05-31 01:01:32.526974 [debug] Server : Finished ClientSaveData from within if. 2016-05-31 01:01:32.526989 [debug] Server : (ServerFSM) ~WaitingForSaveData 2016-05-31 01:01:32.531450 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-05-31 01:01:32.531672 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:32.532046 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 2 2016-05-31 01:01:32.532244 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:32.532267 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:32.532326 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 1 2016-05-31 01:01:32.532445 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:32.532465 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:32.534514 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 5 2016-05-31 01:01:32.534644 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:32.536169 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 4 2016-05-31 01:01:32.536298 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:32.537404 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 3 2016-05-31 01:01:32.537529 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:32.537541 [debug] Server : ServerApp::AllOrdersReceived for turn: 296 2016-05-31 01:01:32.537548 [debug] Server : ... have orders from empire id: 1 2016-05-31 01:01:32.537555 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:32.537562 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:32.537568 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:32.537575 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:32.537582 [debug] Server : WaitingForTurnEnd.TurnOrders : All orders received. 2016-05-31 01:01:32.537590 [debug] Server : (ServerFSM) ~WaitingForTurnEndIdle 2016-05-31 01:01:32.537597 [debug] Server : (ServerFSM) ~WaitingForTurnEnd 2016-05-31 01:01:32.537604 [debug] Server : (ServerFSM) ProcessingTurn 2016-05-31 01:01:32.537611 [debug] Server : (ServerFSM) ProcessingTurn.CheckTurnEndConditions 2016-05-31 01:01:32.537618 [debug] Server : (ServerFSM) ProcessingTurn.CheckTurnEndConditions 2016-05-31 01:01:32.537624 [debug] Server : (ServerFSM) ProcessingTurn.CheckTurnEndConditions 2016-05-31 01:01:32.537630 [debug] Server : (ServerFSM) ProcessingTurn.CheckTurnEndConditions 2016-05-31 01:01:32.537637 [debug] Server : (ServerFSM) ProcessingTurn.ProcessTurn 2016-05-31 01:01:32.537653 [debug] Server : ServerApp::ProcessTurns executing orders 2016-05-31 01:01:32.597205 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 14168 to 214 111 2016-05-31 01:01:32.597286 [debug] Server : Inserting object with id 14169 2016-05-31 01:01:32.622289 [debug] Server : FleetMoveOrder::ExecuteImpl Setting route of fleet 11095 to 250 301 2016-05-31 01:01:32.707554 [debug] Server : ========= Production Update for empire: 1 ======== 2016-05-31 01:01:32.711330 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:32.715182 [debug] Server : ProductionQueue::Update: Projections took 159 microseconds with 465.184 total Production Points 2016-05-31 01:01:32.715284 [debug] Server : ========= Production Update for empire: 2 ======== 2016-05-31 01:01:32.716606 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:32.716945 [debug] Server : ProductionQueue::Update: Projections took 69 microseconds with 1465.87 total Production Points 2016-05-31 01:01:32.716995 [debug] Server : ========= Production Update for empire: 3 ======== 2016-05-31 01:01:32.719953 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:32.722123 [debug] Server : ProductionQueue::Update: Projections took 288 microseconds with 403.425 total Production Points 2016-05-31 01:01:32.722256 [debug] Server : ========= Production Update for empire: 4 ======== 2016-05-31 01:01:32.723050 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:32.724081 [debug] Server : ProductionQueue::Update: Projections took 84 microseconds with 5 total Production Points 2016-05-31 01:01:32.724114 [debug] Server : ========= Production Update for empire: 5 ======== 2016-05-31 01:01:32.729903 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:32.733610 [debug] Server : ProductionQueue::Update: Projections took 3637 microseconds with 1926.52 total Production Points 2016-05-31 01:01:32.733782 [debug] Server : ServerApp::ProcessTurns colonization 2016-05-31 01:01:32.733905 [debug] Server : ServerApp::ProcessTurns invasion 2016-05-31 01:01:32.734462 [debug] Server : ServerApp::ProcessTurns gifting 2016-05-31 01:01:32.734596 [debug] Server : ServerApp::ProcessTurns scrapping 2016-05-31 01:01:32.734753 [debug] Server : ServerApp::ProcessTurns movement 2016-05-31 01:01:32.771968 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:32.782305 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:32.786606 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:32.786938 [debug] Server : Universe::serialize : serializing 141 ship designs 2016-05-31 01:01:32.791380 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:32.824898 [debug] Server : Universe::serialize : serializing 6874 objects 2016-05-31 01:01:32.825275 [debug] Server : Universe::serialize : serializing 6118 destroyed object ids 2016-05-31 01:01:32.825286 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:32.825294 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:32.830492 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:32.830527 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:32.830534 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:32.831374 [debug] Server : Universe::serialize done 2016-05-31 01:01:32.838592 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:32.840704 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:32.841953 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:32.842280 [debug] Server : Universe::serialize : serializing 154 ship designs 2016-05-31 01:01:32.842732 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:32.847030 [debug] Server : Universe::serialize : serializing 604 objects 2016-05-31 01:01:32.847091 [debug] Server : Universe::serialize : serializing 348 destroyed object ids 2016-05-31 01:01:32.847101 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:32.847108 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:32.853104 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:32.853151 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:32.853159 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:32.853318 [debug] Server : Universe::serialize done 2016-05-31 01:01:32.854285 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:32.875931 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:32.883602 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:32.884318 [debug] Server : Universe::serialize : serializing 384 ship designs 2016-05-31 01:01:32.893285 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:32.962530 [debug] Server : Universe::serialize : serializing 12316 objects 2016-05-31 01:01:32.963140 [debug] Server : Universe::serialize : serializing 11573 destroyed object ids 2016-05-31 01:01:32.963151 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:32.963159 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:32.969274 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:32.969321 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:32.969329 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:32.971176 [debug] Server : Universe::serialize done 2016-05-31 01:01:32.986087 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:32.994059 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:32.996973 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:32.997463 [debug] Server : Universe::serialize : serializing 349 ship designs 2016-05-31 01:01:33.000095 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:33.027182 [debug] Server : Universe::serialize : serializing 3921 objects 2016-05-31 01:01:33.027441 [debug] Server : Universe::serialize : serializing 3162 destroyed object ids 2016-05-31 01:01:33.027451 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:33.027459 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:33.033067 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:33.033122 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:33.033136 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:33.033874 [debug] Server : Universe::serialize done 2016-05-31 01:01:33.038324 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:33.058822 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:33.067126 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:33.067883 [debug] Server : Universe::serialize : serializing 278 ship designs 2016-05-31 01:01:33.076839 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:33.134522 [debug] Server : Universe::serialize : serializing 11420 objects 2016-05-31 01:01:33.135165 [debug] Server : Universe::serialize : serializing 10363 destroyed object ids 2016-05-31 01:01:33.135184 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:33.135193 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:33.140274 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:33.140313 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:33.140321 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:33.141875 [debug] Server : Universe::serialize done 2016-05-31 01:01:33.155341 [debug] Server : ServerApp::PreCombatProcessTurns time: 613.084 2016-05-31 01:01:33.155412 [debug] Server : ServerApp::ProcessCombats 2016-05-31 01:01:33.156116 [debug] Server : AutoResolveCombat at Kintana 2016-05-31 01:01:33.186154 [debug] Server : ServerApp::ProcessCombats time: 30.73 2016-05-31 01:01:33.228687 [debug] Server : ServerApp::PostCombatProcessTurns effects and meter updates 2016-05-31 01:01:33.564250 [debug] Server : Issue times: planet species: 0.201 ship species: 0.316 specials: 0.478 techs: 6.045 buildings: 0.556 hulls/parts: 2.303 fields: 0.1 2016-05-31 01:01:33.564318 [debug] Server : Evaluation time: 729.752 reorder time: 31.28 2016-05-31 01:01:33.632788 [debug] Server : Universe::ExecuteEffects time: 55.719 2016-05-31 01:01:33.639010 [debug] Server : ServerApp::PostCombatProcessTurns empire resources updates 2016-05-31 01:01:33.662080 [debug] Server : ========= Production Update for empire: 1 ======== 2016-05-31 01:01:33.668380 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:33.672691 [debug] Server : ProductionQueue::Update: Projections took 205 microseconds with 465.184 total Production Points 2016-05-31 01:01:33.683388 [debug] Server : ========= Production Update for empire: 2 ======== 2016-05-31 01:01:33.685595 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:33.686103 [debug] Server : ProductionQueue::Update: Projections took 104 microseconds with 1470.4 total Production Points 2016-05-31 01:01:33.738223 [debug] Server : ========= Production Update for empire: 3 ======== 2016-05-31 01:01:33.741697 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:33.743805 [debug] Server : ProductionQueue::Update: Projections took 374 microseconds with 411.225 total Production Points 2016-05-31 01:01:33.759669 [debug] Server : ========= Production Update for empire: 4 ======== 2016-05-31 01:01:33.760752 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:33.761541 [debug] Server : ProductionQueue::Update: Projections took 72 microseconds with 5 total Production Points 2016-05-31 01:01:33.766037 [debug] Server : ========= Production Update for empire: 5 ======== 2016-05-31 01:01:33.772891 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-05-31 01:01:33.776729 [debug] Server : ProductionQueue::Update: Projections took 3772 microseconds with 1948.52 total Production Points 2016-05-31 01:01:33.781805 [debug] Server : ServerApp::PostCombatProcessTurns queue progress checking 2016-05-31 01:01:33.781894 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:33.786378 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:33.786528 [debug] Server : Location test failed for BuildingType BLD_COL_EXOBOT at location Sssla α I 2016-05-31 01:01:33.787204 [debug] Server : Location test failed for Ships(s) with design id 540 at location Kintana I 2016-05-31 01:01:33.788907 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:33.789978 [debug] Server : Completed an item: AY Storm Troopers Mk. 1 2016-05-31 01:01:33.790095 [debug] Server : New block of 4 ships created on turn: 296 2016-05-31 01:01:33.790107 [debug] Server : Marking completed production queue item to be removed form queue 2016-05-31 01:01:33.790200 [debug] Server : Location test failed for Ships(s) with design id 438 at location Gion I 2016-05-31 01:01:33.790217 [debug] Server : Location test failed for Ships(s) with design id 65 at location Oasis β II 2016-05-31 01:01:33.790305 [debug] Server : Location test failed for Ships(s) with design id 593 at location Gion I 2016-05-31 01:01:33.790390 [debug] Server : Location test failed for Ships(s) with design id 593 at location Gion I 2016-05-31 01:01:33.791291 [debug] Server : New Fleet "Fleet 14174" created on turn: 296 2016-05-31 01:01:33.792990 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:33.793162 [debug] Server : Location test failed for Ships(s) with design id 43 at location Reul α II 2016-05-31 01:01:33.793260 [debug] Server : Location test failed for Ships(s) with design id 213 at location Royal Girtab α I 2016-05-31 01:01:33.793351 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:33.793491 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:33.793595 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:33.793683 [debug] Server : Location test failed for Ships(s) with design id 56 at location Makina α Asteroids 2016-05-31 01:01:33.793913 [debug] Server : Location test failed for Ships(s) with design id 214 at location Makina α II 2016-05-31 01:01:33.794136 [debug] Server : Location test failed for Ships(s) with design id 246 at location Makina α II 2016-05-31 01:01:33.794227 [debug] Server : Location test failed for Ships(s) with design id 214 at location Makina α Asteroids 2016-05-31 01:01:33.794427 [debug] Server : ========Empire::CheckProductionProgress======= 2016-05-31 01:01:33.799780 [debug] Server : Location test failed for BuildingType BLD_COL_UGMORS at location Reul β I 2016-05-31 01:01:33.799967 [debug] Server : Python current directory set to /home/andrew/Games/freeorion/default/python/turn_events 2016-05-31 01:01:33.799996 [debug] Server : Executing turn events for turn 296 2016-05-31 01:01:34.162174 [debug] Server : Issue times: planet species: 0.184 ship species: 0.363 specials: 0.62 techs: 8.363 buildings: 0.653 hulls/parts: 2.727 fields: 0.078 2016-05-31 01:01:34.162236 [debug] Server : Evaluation time: 697.867 reorder time: 31.612 2016-05-31 01:01:34.230548 [debug] Server : Universe::ExecuteEffects time: 56.292 2016-05-31 01:01:34.349838 [debug] Server : ServerApp::PostCombatProcessTurns Turn number incremented to 297 2016-05-31 01:01:34.398291 [debug] Server : ServerApp::PostCombatProcessTurns Sending turn updates to players 2016-05-31 01:01:34.460614 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:34.473524 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:34.477553 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:34.477959 [debug] Server : Universe::serialize : serializing 142 ship designs 2016-05-31 01:01:34.483051 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:34.519849 [debug] Server : Universe::serialize : serializing 6879 objects 2016-05-31 01:01:34.520285 [debug] Server : Universe::serialize : serializing 6118 destroyed object ids 2016-05-31 01:01:34.520299 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:34.520307 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:34.525182 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:34.525228 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:34.525236 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:34.526146 [debug] Server : Universe::serialize done 2016-05-31 01:01:34.602550 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:34.603410 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:34.604080 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:34.604390 [debug] Server : Universe::serialize : serializing 154 ship designs 2016-05-31 01:01:34.604799 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:34.609393 [debug] Server : Universe::serialize : serializing 604 objects 2016-05-31 01:01:34.609434 [debug] Server : Universe::serialize : serializing 348 destroyed object ids 2016-05-31 01:01:34.609443 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:34.609450 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:34.613538 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:34.613564 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:34.613571 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:34.613671 [debug] Server : Universe::serialize done 2016-05-31 01:01:34.716202 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:34.735056 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:34.740431 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:34.741433 [debug] Server : Universe::serialize : serializing 385 ship designs 2016-05-31 01:01:34.750950 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:34.825693 [debug] Server : Universe::serialize : serializing 12321 objects 2016-05-31 01:01:34.826512 [debug] Server : Universe::serialize : serializing 11575 destroyed object ids 2016-05-31 01:01:34.826533 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:34.826541 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:34.832455 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:34.832497 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:34.832504 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:34.834090 [debug] Server : Universe::serialize done 2016-05-31 01:01:34.996954 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:35.007761 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:35.019813 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:35.020739 [debug] Server : Universe::serialize : serializing 350 ship designs 2016-05-31 01:01:35.024363 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:35.068737 [debug] Server : Universe::serialize : serializing 3926 objects 2016-05-31 01:01:35.069050 [debug] Server : Universe::serialize : serializing 3164 destroyed object ids 2016-05-31 01:01:35.069068 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:35.069077 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:35.075041 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:35.075087 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:35.075095 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:35.075724 [debug] Server : Universe::serialize done 2016-05-31 01:01:35.184527 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:35.240769 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:35.257619 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:35.259499 [debug] Server : Universe::serialize : serializing 278 ship designs 2016-05-31 01:01:35.285475 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-05-31 01:01:35.389002 [debug] Server : Universe::serialize : serializing 11425 objects 2016-05-31 01:01:35.389651 [debug] Server : Universe::serialize : serializing 10365 destroyed object ids 2016-05-31 01:01:35.389666 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-05-31 01:01:35.389673 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:35.395642 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:35.395680 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:35.395688 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:35.397192 [debug] Server : Universe::serialize done 2016-05-31 01:01:35.423774 [debug] Server : ServerApp::PostCombatProcessTurns done 2016-05-31 01:01:35.423834 [debug] Server : ServerApp::PostCombatProcessTurns time: 2817.36 2016-05-31 01:01:35.424459 [debug] Server : (ServerFSM) ~ProcessingTurn 2016-05-31 01:01:35.424472 [debug] Server : (ServerFSM) WaitingForTurnEnd 2016-05-31 01:01:35.424481 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-05-31 01:01:35.424513 [debug] Server : Server received message from player id: 1 of type Save Game and size 100 2016-05-31 01:01:35.424532 [debug] Server : (ServerFSM) WaitingForTurnEndIdle.SaveGameRequest 2016-05-31 01:01:35.424541 [debug] Server : (ServerFSM) ~WaitingForTurnEndIdle 2016-05-31 01:01:35.424550 [debug] Server : (ServerFSM) WaitingForSaveData 2016-05-31 01:01:36.174057 [debug] Server : Server received message from player id: 2 of type Turn Orders and size 2088 2016-05-31 01:01:36.174499 [debug] Server : Server received message from player id: 1 of type Client Save Data and size 727 2016-05-31 01:01:36.174523 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:36.174592 [debug] Server : deserializing orders 2016-05-31 01:01:36.174627 [debug] Server : checking for ui data 2016-05-31 01:01:36.174639 [debug] Server : deserializing UI data 2016-05-31 01:01:36.174673 [debug] Server : checking for save state string 2016-05-31 01:01:36.174700 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:36.242045 [debug] Server : Server received message from player id: 2 of type Client Save Data and size 67518 2016-05-31 01:01:36.242095 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:36.242183 [debug] Server : deserializing orders 2016-05-31 01:01:36.242218 [debug] Server : checking for ui data 2016-05-31 01:01:36.242232 [debug] Server : checking for save state string 2016-05-31 01:01:36.242244 [debug] Server : deserializing save state string 2016-05-31 01:01:36.243899 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:38.212151 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-05-31 01:01:38.213552 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-05-31 01:01:38.215092 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-05-31 01:01:40.010352 [debug] Server : Server received message from player id: 5 of type Player Chat and size 122 2016-05-31 01:01:40.596417 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-05-31 01:01:40.975752 [debug] Server : Server received message from player id: 4 of type Player Chat and size 122 2016-05-31 01:01:41.844377 [debug] Server : Server received message from player id: 5 of type Turn Orders and size 29751 2016-05-31 01:01:42.065152 [debug] Server : Server received message from player id: 5 of type Client Save Data and size 247401 2016-05-31 01:01:42.065205 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:42.065415 [debug] Server : deserializing orders 2016-05-31 01:01:42.065454 [debug] Server : checking for ui data 2016-05-31 01:01:42.065467 [debug] Server : checking for save state string 2016-05-31 01:01:42.065479 [debug] Server : deserializing save state string 2016-05-31 01:01:42.076363 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:42.453794 [debug] Server : Server received message from player id: 4 of type Turn Orders and size 23746 2016-05-31 01:01:42.668837 [debug] Server : Server received message from player id: 4 of type Client Save Data and size 323849 2016-05-31 01:01:42.668907 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:42.669109 [debug] Server : deserializing orders 2016-05-31 01:01:42.669147 [debug] Server : checking for ui data 2016-05-31 01:01:42.669160 [debug] Server : checking for save state string 2016-05-31 01:01:42.669172 [debug] Server : deserializing save state string 2016-05-31 01:01:42.682935 [debug] Server : Finished ClientSaveData from outside of if. 2016-05-31 01:01:43.450556 [debug] Server : Server received message from player id: 3 of type Turn Orders and size 16640 2016-05-31 01:01:43.595152 [debug] Server : Server received message from player id: 3 of type Client Save Data and size 250772 2016-05-31 01:01:43.595284 [debug] Server : (ServerFSM) WaitingForSaveData.ClientSaveData 2016-05-31 01:01:43.595540 [debug] Server : deserializing orders 2016-05-31 01:01:43.595601 [debug] Server : checking for ui data 2016-05-31 01:01:43.595622 [debug] Server : checking for save state string 2016-05-31 01:01:43.595642 [debug] Server : deserializing save state string 2016-05-31 01:01:43.602508 [debug] Server : SaveGame(zlib-xml) filename: /home/andrew/.local/share/freeorion/save/auto/FreeOrion_Human_Player_Terran_0297_20160531_010134.sav 2016-05-31 01:01:43.602548 [debug] Server : Compiling save empire and preview data 2016-05-31 01:01:43.602674 [debug] Server : Allocating buffers for XML serialization... 2016-05-31 01:01:44.503165 [debug] Server : Universe::serialize : Getting gamestate data 2016-05-31 01:01:44.504452 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-05-31 01:01:44.528042 [debug] Server : Universe::serialize : serializing universe width: 1008.9 2016-05-31 01:01:44.542791 [debug] Server : Universe::serialize : serializing 717 ship designs 2016-05-31 01:01:44.860308 [debug] Server : Universe::serialize : serializing empire object visibility for 5, 5, 5, 5 empires 2016-05-31 01:01:44.950455 [debug] Server : Universe::serialize : serializing 1037 objects 2016-05-31 01:01:44.957231 [debug] Server : Universe::serialize : serializing 13013 destroyed object ids 2016-05-31 01:01:47.019968 [debug] Server : Universe::serialize : serializing empire known objects for 5 empires 2016-05-31 01:01:47.020016 [debug] Server : Universe::serialize : serializing last allocated ids 2016-05-31 01:01:47.102305 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-05-31 01:01:47.102351 [debug] Server : Universe::serialize : serializing done 2016-05-31 01:01:47.102360 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-05-31 01:01:47.106746 [debug] Server : Universe::serialize done 2016-05-31 01:01:48.538308 [debug] Server : SaveGame : Successfully wrote save file 2016-05-31 01:01:48.538360 [debug] Server : SaveGame: /home/andrew/.local/share/freeorion/save/auto/FreeOrion_Human_Player_Terran_0297_20160531_010134.sav time: 4894.72 2016-05-31 01:01:48.538385 [debug] Server : Finished ClientSaveData from within if. 2016-05-31 01:01:48.538394 [debug] Server : (ServerFSM) ~WaitingForSaveData 2016-05-31 01:01:48.543501 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-05-31 01:01:48.543557 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:48.544351 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 2 2016-05-31 01:01:48.546323 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:48.546362 [debug] Server : (ServerFSM) PlayingGame.PlayerChat 2016-05-31 01:01:48.546387 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:48.548624 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 5 2016-05-31 01:01:48.554258 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:48.556038 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 4 2016-05-31 01:01:48.556201 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-05-31 01:01:48.557473 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 3 2016-05-31 01:01:48.566933 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:48.566968 [debug] Server : ServerApp::AllOrdersReceived for turn: 297 2016-05-31 01:01:48.566977 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:48.566985 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:48.566993 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:48.567000 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:48.567007 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:48.567015 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:48.567022 [debug] Server : ServerApp::AllOrdersReceived for turn: 297 2016-05-31 01:01:48.567041 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:48.567049 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:48.567056 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:48.567063 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:48.567071 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:48.567078 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:48.567085 [debug] Server : ServerApp::AllOrdersReceived for turn: 297 2016-05-31 01:01:48.567093 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:48.567100 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:48.567107 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:48.567114 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:48.567120 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:48.567128 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-05-31 01:01:48.567134 [debug] Server : ServerApp::AllOrdersReceived for turn: 297 2016-05-31 01:01:48.567141 [debug] Server : ... no orders from empire id: 1 2016-05-31 01:01:48.567148 [debug] Server : ... have orders from empire id: 2 2016-05-31 01:01:48.567155 [debug] Server : ... have orders from empire id: 3 2016-05-31 01:01:48.567161 [debug] Server : ... have orders from empire id: 4 2016-05-31 01:01:48.567168 [debug] Server : ... have orders from empire id: 5 2016-05-31 01:01:51.088982 [debug] Server : Server received message from player id: 1 of type Shut Down Server and size 8 2016-05-31 01:01:51.089046 [debug] Server : ServerApp::HandleShutdownMessage shutting down 2016-05-31 01:01:51.089055 [debug] Server : Initiating Exit (code 1 - error termination) 2016-05-31 01:01:51.089063 [debug] Server : ServerApp::CleanupAIs() telling AIs game is ending 2016-05-31 01:01:51.089179 [debug] Server : ServerApp::CleanupAIs() waiting 1 second for AI processes to clean up... 2016-05-31 01:01:52.089275 [debug] Server : ServerApp::CleanupAIs() killing 4 AI clients. 2016-05-31 01:01:52.089347 [debug] Server : Process::Kill 2016-05-31 01:01:52.089361 [debug] Server : Process::Kill calling m_impl->Kill() 2016-05-31 01:01:52.089374 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089400 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089436 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089449 [debug] Server : Process::Kill calling RequestTermination() 2016-05-31 01:01:52.089464 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089474 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089486 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089504 [debug] Server : Process::Kill 2016-05-31 01:01:52.089516 [debug] Server : Process::Kill calling m_impl->Kill() 2016-05-31 01:01:52.089526 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089539 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089561 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089574 [debug] Server : Process::Kill calling RequestTermination() 2016-05-31 01:01:52.089586 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089598 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089610 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089622 [debug] Server : Process::Kill 2016-05-31 01:01:52.089633 [debug] Server : Process::Kill calling m_impl->Kill() 2016-05-31 01:01:52.089644 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089656 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089693 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089706 [debug] Server : Process::Kill calling RequestTermination() 2016-05-31 01:01:52.089720 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089733 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089744 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089755 [debug] Server : Process::Kill 2016-05-31 01:01:52.089764 [debug] Server : Process::Kill calling m_impl->Kill() 2016-05-31 01:01:52.089770 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089777 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089789 [debug] Server : Process::Impl::Kill done 2016-05-31 01:01:52.089796 [debug] Server : Process::Kill calling RequestTermination() 2016-05-31 01:01:52.089803 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-05-31 01:01:52.089809 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-05-31 01:01:52.089816 [debug] Server : Process::Impl::Kill done