2016-03-02 20:29:03.268141 [debug] Server : Logger initialized 2016-03-02 20:29:03.268398 [debug] Server : v0.4.5+ [build 2016-03-01.e7f05d8] CMake 2016-03-02 20:29:03.268477 [debug] Server : Dependency versions from headers: 2016-03-02 20:29:03.268495 [debug] Server : Boost: 1_58 2016-03-02 20:29:03.268511 [debug] Server : FreeType: 2.5.2 2016-03-02 20:29:03.268543 [debug] Server : PNG: 1.2.50 2016-03-02 20:29:03.268559 [debug] Server : Python: 2.7.6 2016-03-02 20:29:03.268571 [debug] Server : SDL: 2.0.2 2016-03-02 20:29:03.268584 [debug] Server : zlib: 1.2.8 2016-03-02 20:29:03.268608 [debug] Server : (ServerFSM) Idle 2016-03-02 20:29:03.268645 [debug] Server : Initializing FreeOrion Python interface 2016-03-02 20:29:03.284493 [debug] Server : Python initialized 2016-03-02 20:29:03.284592 [debug] Server : Python version: 2.7.6 (default, Jun 22 2015, 18:01:27) [GCC 4.8.2] 2016-03-02 20:29:03.284620 [debug] Server : Python prefix: /usr 2016-03-02 20:29:03.284641 [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-03-02 20:29:03.284657 [debug] Server : Initializing C++ interfaces for Python 2016-03-02 20:29:03.285371 [debug] Server : Python stdout and stderr redirected 2016-03-02 20:29:03.285415 [debug] Server : Initializing server Python modules 2016-03-02 20:29:04.456160 [debug] Server : Server Python modules successfully initialized! 2016-03-02 20:29:04.456260 [debug] Server : FreeOrion Python interface successfully initialized! 2016-03-02 20:29:04.456285 [debug] Server : FreeOrion server waiting for network events 2016-03-02 20:29:04.456379 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-03-02 20:29:04.456428 [debug] Server : Server received message from player id: -1 of type Host SP Game and size 983 2016-03-02 20:29:04.456467 [debug] Server : (ServerFSM) Idle.HostSPGame 2016-03-02 20:29:04.490780 [debug] Server : Reading player save game data from: /home/juls/.freeorion/save/auto/FreeOrion_Scara_Terra_0174_20160302_195219.sav 2016-03-02 20:29:04.490858 [debug] Server : Attempting binary deserialization... 2016-03-02 20:29:04.491026 [debug] Server : Trying again with XML deserialization... 2016-03-02 20:29:04.491279 [debug] Server : Done reading player save game data... 2016-03-02 20:29:04.491310 [debug] Server : PlayerConnection(@ 0x1bb7a00)::EstablishPlayer(1, Scara, 1,v0.4.5+ [build 2016-03-01.e7f05d8] CMake) 2016-03-02 20:29:04.491447 [debug] Server : (ServerFSM) ~Idle 2016-03-02 20:29:04.491468 [debug] Server : (ServerFSM) WaitingForSPGameJoiners 2016-03-02 20:29:04.491519 [debug] Server : Reading player save game data from: /home/juls/.freeorion/save/auto/FreeOrion_Scara_Terra_0174_20160302_195219.sav 2016-03-02 20:29:04.491545 [debug] Server : Attempting binary deserialization... 2016-03-02 20:29:04.491591 [debug] Server : Trying again with XML deserialization... 2016-03-02 20:29:04.491782 [debug] Server : Done reading player save game data... 2016-03-02 20:29:04.491810 [debug] Server : ServerApp::CreateAIClients: 3 player (maybe not all AIs) at max aggression: 5 2016-03-02 20:29:04.491922 [debug] Server : ServerApp::CleanupAIs() telling AIs game is ending 2016-03-02 20:29:04.491940 [debug] Server : ServerApp::CleanupAIs() killing 0 AI clients. 2016-03-02 20:29:04.491976 [debug] Server : starting /home/juls/Schreibtisch/alles/spiele/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-03-02 20:29:04.491991 [debug] Server : ai-path set to 'AI' 2016-03-02 20:29:04.492003 [debug] Server : ai-config not set. 2016-03-02 20:29:05.492643 [debug] Server : done starting /home/juls/Schreibtisch/alles/spiele/freeorion/freeorionca 2016-03-02 20:29:05.492799 [debug] Server : starting /home/juls/Schreibtisch/alles/spiele/freeorion/freeorionca with GameSetup.ai-aggression set to 5 2016-03-02 20:29:05.493211 [debug] Server : ai-path set to 'AI' 2016-03-02 20:29:05.493235 [debug] Server : ai-config not set. 2016-03-02 20:29:06.493899 [debug] Server : done starting /home/juls/Schreibtisch/alles/spiele/freeorion/freeorionca 2016-03-02 20:29:06.494041 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-03-02 20:29:06.494089 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-03-02 20:29:06.494141 [debug] Server : ServerNetworking::AcceptConnection : connected to new player 2016-03-02 20:29:06.494176 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-03-02 20:29:06.494209 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-03-02 20:29:06.494227 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-03-02 20:29:06.494373 [debug] Server : PlayerConnection(@ 0x1bddae0)::EstablishPlayer(2, AI_2, 0,v0.4.5+ [build 2016-03-01.e7f05d8] CMake) 2016-03-02 20:29:06.494470 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-03-02 20:29:06.494499 [debug] Server : Server received message from player id: -1 of type Join Game and size 331 2016-03-02 20:29:06.494521 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.JoinGame 2016-03-02 20:29:06.494535 [debug] Server : ExtractMessageData() from Default_Player_Name_in_WaitingForSPGameJoiners::react(const JoinGame& msg) client type -1 2016-03-02 20:29:06.494612 [debug] Server : PlayerConnection(@ 0x232bcd0)::EstablishPlayer(3, AI_1, 0,v0.4.5+ [build 2016-03-01.e7f05d8] CMake) 2016-03-02 20:29:06.494663 [debug] Server : (ServerFSM) WaitingForSPGameJoiners.CheckStartConditions 2016-03-02 20:29:06.494682 [debug] Server : WaitingForSPGameJoiners::react(const CheckStartConditions& u) : have all 3 expected players connected. 2016-03-02 20:29:06.494699 [debug] Server : Loading SP game save file: /home/juls/.freeorion/save/auto/FreeOrion_Scara_Terra_0174_20160302_195219.sav 2016-03-02 20:29:06.494998 [debug] Server : Allocating buffers for XML deserialization... 2016-03-02 20:29:07.053132 [debug] Server : Universe::serialize : deserializing universe width: 1172.24 2016-03-02 20:29:07.619973 [debug] Server : Universe::serialize : deserializing 0 ship designs 2016-03-02 20:29:07.705938 [debug] Server : Universe::serialize : deserializing empire object visibility for 3, 3, 3, 3 empires 2016-03-02 20:29:07.864156 [debug] Server : Universe::serialize : deserializing 700 objects 2016-03-02 20:29:07.866071 [debug] Server : Universe::serialize : deserializing 1050 destroyed object ids 2016-03-02 20:29:08.363197 [debug] Server : Universe::serialize : deserializing empire known objects for 3 empires 2016-03-02 20:29:08.363262 [debug] Server : Universe::serialize : deserializing last allocated ids 2016-03-02 20:29:08.429991 [debug] Server : Universe::serialize : deserializing 18 types of statistic 2016-03-02 20:29:08.430048 [debug] Server : Universe::serialize : deserializing done 2016-03-02 20:29:08.430061 [debug] Server : Universe::serialize : updating empires' latest known object destruction states 2016-03-02 20:29:08.431177 [debug] Server : Universe::serialize done 2016-03-02 20:29:08.432681 [debug] Server : LoadGame : Successfully loaded save file 2016-03-02 20:29:08.432730 [debug] Server : LoadGame: /home/juls/.freeorion/save/auto/FreeOrion_Scara_Terra_0174_20160302_195219.sav time: 1909.02 2016-03-02 20:29:08.432768 [debug] Server : ServerApp::LoadGameInit 2016-03-02 20:29:08.439578 [debug] Server : ========= Production Update for empire: 1 ======== 2016-03-02 20:29:09.256037 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-03-02 20:29:09.262578 [debug] Server : ProductionQueue::Update: Projections took 5393 microseconds with 365.779 total Production Points 2016-03-02 20:29:09.279853 [debug] Server : ========= Production Update for empire: 2 ======== 2016-03-02 20:29:09.286743 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-03-02 20:29:09.293010 [debug] Server : ProductionQueue::Update: Projections took 5462 microseconds with 248.534 total Production Points 2016-03-02 20:29:09.312882 [debug] Server : ========= Production Update for empire: 3 ======== 2016-03-02 20:29:09.313397 [debug] Server : ProductionQueue::Update: Simulating future turns of production queue 2016-03-02 20:29:09.313616 [debug] Server : ProductionQueue::Update: Projections took 51 microseconds with 88.0163 total Production Points 2016-03-02 20:29:09.314383 [debug] Server : ServerApp::GetPlayerInfoMap: Compiling PlayerInfo for each player 2016-03-02 20:29:09.314442 [debug] Server : ServerApp::CommonGameInit: Sending GameStartMessages to players 2016-03-02 20:29:09.329963 [debug] Server : Universe::serialize : Getting gamestate data 2016-03-02 20:29:09.332084 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-03-02 20:29:09.333083 [debug] Server : Universe::serialize : serializing universe width: 1172.24 2016-03-02 20:29:09.333379 [debug] Server : Universe::serialize : serializing 107 ship designs 2016-03-02 20:29:09.335354 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-03-02 20:29:09.347034 [debug] Server : Universe::serialize : serializing 1203 objects 2016-03-02 20:29:09.347198 [debug] Server : Universe::serialize : serializing 549 destroyed object ids 2016-03-02 20:29:09.347256 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-03-02 20:29:09.347300 [debug] Server : Universe::serialize : serializing last allocated ids 2016-03-02 20:29:09.349750 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-03-02 20:29:09.349821 [debug] Server : Universe::serialize : serializing done 2016-03-02 20:29:09.349863 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-03-02 20:29:09.350060 [debug] Server : Universe::serialize done 2016-03-02 20:29:09.367107 [debug] Server : Universe::serialize : Getting gamestate data 2016-03-02 20:29:09.368387 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-03-02 20:29:09.369111 [debug] Server : Universe::serialize : serializing universe width: 1172.24 2016-03-02 20:29:09.369480 [debug] Server : Universe::serialize : serializing 134 ship designs 2016-03-02 20:29:09.370530 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-03-02 20:29:09.378375 [debug] Server : Universe::serialize : serializing 713 objects 2016-03-02 20:29:09.378446 [debug] Server : Universe::serialize : serializing 418 destroyed object ids 2016-03-02 20:29:09.378463 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-03-02 20:29:09.378476 [debug] Server : Universe::serialize : serializing last allocated ids 2016-03-02 20:29:09.380948 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-03-02 20:29:09.380988 [debug] Server : Universe::serialize : serializing done 2016-03-02 20:29:09.381000 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-03-02 20:29:09.381112 [debug] Server : Universe::serialize done 2016-03-02 20:29:09.400411 [debug] Server : Universe::serialize : Getting gamestate data 2016-03-02 20:29:09.401731 [debug] Server : GetEmpireKnownObjectsToSerialize 2016-03-02 20:29:09.407820 [debug] Server : Universe::serialize : serializing universe width: 1172.24 2016-03-02 20:29:09.408244 [debug] Server : Universe::serialize : serializing 137 ship designs 2016-03-02 20:29:09.409576 [debug] Server : Universe::serialize : serializing empire object visibility for 1, 1, 1, 1 empires 2016-03-02 20:29:09.417031 [debug] Server : Universe::serialize : serializing 702 objects 2016-03-02 20:29:09.417123 [debug] Server : Universe::serialize : serializing 423 destroyed object ids 2016-03-02 20:29:09.417141 [debug] Server : Universe::serialize : serializing empire known objects for 0 empires 2016-03-02 20:29:09.417154 [debug] Server : Universe::serialize : serializing last allocated ids 2016-03-02 20:29:09.419750 [debug] Server : Universe::serialize : serializing 18 types of statistic 2016-03-02 20:29:09.419822 [debug] Server : Universe::serialize : serializing done 2016-03-02 20:29:09.419858 [debug] Server : Universe::serialize : Cleaning up temporary data 2016-03-02 20:29:09.420016 [debug] Server : Universe::serialize done 2016-03-02 20:29:09.422590 [debug] Server : (ServerFSM) ~WaitingForSPGameJoiners 2016-03-02 20:29:09.422673 [debug] Server : (ServerFSM) PlayingGame 2016-03-02 20:29:09.422688 [debug] Server : (ServerFSM) WaitingForTurnEnd 2016-03-02 20:29:09.422700 [debug] Server : (ServerFSM) WaitingForTurnEndIdle 2016-03-02 20:29:14.328682 [debug] Server : Server received message from player id: 2 of type Turn Orders and size 6859 2016-03-02 20:29:14.328766 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-03-02 20:29:14.329442 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 2 2016-03-02 20:29:14.329606 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-03-02 20:29:14.329622 [debug] Server : ServerApp::AllOrdersReceived for turn: 174 2016-03-02 20:29:14.329634 [debug] Server : ... no orders from empire id: 1 2016-03-02 20:29:14.329646 [debug] Server : ... no orders from empire id: 2 2016-03-02 20:29:14.329657 [debug] Server : ... have orders from empire id: 3 2016-03-02 20:29:17.419206 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-03-02 20:29:17.431760 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-03-02 20:29:17.433311 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-03-02 20:29:17.433717 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-03-02 20:29:17.552248 [debug] Server : Server received message from player id: 3 of type Request New Object ID and size 8 2016-03-02 20:29:18.215734 [debug] Server : Server received message from player id: 3 of type Turn Orders and size 47339 2016-03-02 20:29:18.215794 [debug] Server : (ServerFSM) WaitingForTurnEnd.TurnOrders 2016-03-02 20:29:18.220325 [debug] Server : WaitingForTurnEnd.TurnOrders : Received orders from player 3 2016-03-02 20:29:18.220758 [debug] Server : (ServerFSM) WaitingForTurnEnd.CheckTurnEndConditions 2016-03-02 20:29:18.220787 [debug] Server : ServerApp::AllOrdersReceived for turn: 174 2016-03-02 20:29:18.220803 [debug] Server : ... no orders from empire id: 1 2016-03-02 20:29:18.220817 [debug] Server : ... have orders from empire id: 2 2016-03-02 20:29:18.220830 [debug] Server : ... have orders from empire id: 3 2016-03-02 20:35:42.532693 [debug] Server : ServerNetworking::DisconnectImpl : disconnecting player 1 2016-03-02 20:35:42.532771 [debug] Server : ServerFSM::HandleNonLobbyDisconnection : Lost connection to player #1, named "Scara"; server terminating. 2016-03-02 20:35:42.532908 [debug] Server : ServerFSM::HandleNonLobbyDisconnection : All human players disconnected; server terminating. 2016-03-02 20:35:44.533011 [debug] Server : Initiating Exit (code 1 - error termination) 2016-03-02 20:35:44.533083 [debug] Server : ServerApp::CleanupAIs() telling AIs game is ending 2016-03-02 20:35:44.533238 [debug] Server : ServerApp::CleanupAIs() waiting 1 second for AI processes to clean up... 2016-03-02 20:35:45.533340 [debug] Server : ServerApp::CleanupAIs() killing 2 AI clients. 2016-03-02 20:35:45.533417 [debug] Server : Process::Kill 2016-03-02 20:35:45.533435 [debug] Server : Process::Kill calling m_impl->Kill() 2016-03-02 20:35:45.533451 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-03-02 20:35:45.533476 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-03-02 20:35:45.533534 [debug] Server : Process::Impl::Kill done 2016-03-02 20:35:45.533552 [debug] Server : Process::Kill calling RequestTermination() 2016-03-02 20:35:45.533569 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-03-02 20:35:45.533584 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-03-02 20:35:45.533598 [debug] Server : Process::Impl::Kill done 2016-03-02 20:35:45.533628 [debug] Server : Process::Kill 2016-03-02 20:35:45.533641 [debug] Server : Process::Kill calling m_impl->Kill() 2016-03-02 20:35:45.533654 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-03-02 20:35:45.533670 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-03-02 20:35:45.533694 [debug] Server : Process::Impl::Kill done 2016-03-02 20:35:45.533713 [debug] Server : Process::Kill calling RequestTermination() 2016-03-02 20:35:45.533726 [debug] Server : Process::Impl::Kill calling kill(m_process_id, SIGKILL) 2016-03-02 20:35:45.533740 [debug] Server : Process::Impl::Kill calling waitpid(m_process_id, &status, 0) 2016-03-02 20:35:45.533754 [debug] Server : Process::Impl::Kill done