Server not accepting players

Problems and solutions for installing or running FreeOrion, including discussion of bugs if needed before posting a bug report on GitHub. For problems building from source, post in Compile.

Moderator: Oberlus

Post Reply
Message
Author
Yithazr
Space Krill
Posts: 4
Joined: Fri Jun 17, 2022 11:46 am

Server not accepting players

#1 Post by Yithazr »

When I try to load a saved game I get:
The server is not currently accepting players because a game is being played.
A game is not being played. If I exit the game, ps shows no process with "rion" in the name. Presumably there is a lock file I need to delete. Does anyone know the path? I am using Debian Bullseye and FreeOrion 0.4.10.1 but if you know the path for other OS's, please post it in case others find the same problem.

Thanks for a great game and for you help.

o01eg
Programmer
Posts: 2004
Joined: Sat Dec 10, 2011 5:46 am

Re: Server not accepting players

#2 Post by o01eg »

Yithazr wrote: Fri Jun 17, 2022 12:14 pm When I try to load a saved game I get:
The server is not currently accepting players because a game is being played.
A game is not being played. If I exit the game, ps shows no process with "rion" in the name. Presumably there is a lock file I need to delete. Does anyone know the path? I am using Debian Bullseye and FreeOrion 0.4.10.1 but if you know the path for other OS's, please post it in case others find the same problem.

Thanks for a great game and for you help.
In ~/.local/share/freeorion/ should be freeorion.log and freeoriond.log which could help us to determine reason of error.
Gentoo Linux x64, gcc-11.2, boost-1.78.0
Ubuntu Server 22.04 x64, gcc-12, boost-1.74.0
Welcome to the slow multiplayer game at freeorion-lt.dedyn.io.Version 2024-03-15.b3de094.
Donations're welcome:BTC:bc1q007qldm6eppqcukewtfkfcj0naut9njj7audnm

Yithazr
Space Krill
Posts: 4
Joined: Fri Jun 17, 2022 11:46 am

Re: Server not accepting players

#3 Post by Yithazr »

Log files as requested
- adding log files to the post as attachments fails.
- editing the post to add attachments failed repeatedly
I will try using xclip to put the logs in the body of replies...
That did not work either because of a 60,000 character limit.
Split the demon log in half and posted the first half, which worked. The second half did not work twice. Probably some kind of anti-spam defence.
I could use sed or grep to trim the logs so they only contain things of interest. Any sugestions?

Tried the save game from the previous turn and that loaded fine. Looks like the error message is misleading.
Last edited by Yithazr on Fri Jun 17, 2022 3:50 pm, edited 2 times in total.

Yithazr
Space Krill
Posts: 4
Joined: Fri Jun 17, 2022 11:46 am

Re: Server not accepting players

#4 Post by Yithazr »

12:27:31.340541 {0x00007f6c7d7f6780} [info] log : Logger.cpp:200 : Added logger named "log"
12:27:31.340838 {0x00007f6c7d7f6780} [info] log : Logger.cpp:200 : Added logger named "IDallocator"
12:27:31.340874 {0x00007f6c7d7f6780} [info] log : Logger.cpp:380 : Logger initialized at Fri Jun 17 12:27:31 2022
12:27:31.341006 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "log" logger threshold to "debug".
12:27:31.341045 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "log" from optionsDB using threshold debug
12:27:31.341132 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "server" logger threshold to "debug".
12:27:31.341214 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "IDallocator" logger threshold to "debug".
12:27:31.341244 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "IDallocator" from optionsDB using threshold debug
12:27:31.341280 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "log" logger threshold to "debug".
12:27:31.341306 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "log" from optionsDB using threshold debug
12:27:31.341333 {0x00007f6c7d7f6780} [info] log : LoggerWithOptionsDB.cpp:91 : Initialized OptionsDB logging configuration.
12:27:31.341377 {0x00007f6c7d7f6780} [info] server : ServerApp.cpp:104 : v0.4.10.1 [build 2020-09-25.39cfe10] CMake
12:27:31.341436 {0x00007f6c7d7f6780} [info] server : DependencyVersions.cpp:81 : Dependency versions from headers:
12:27:31.341481 {0x00007f6c7d7f6780} [info] server : DependencyVersions.cpp:84 : Boost: 1_74
12:27:31.341511 {0x00007f6c7d7f6780} [info] server : DependencyVersions.cpp:84 : Python: 3.9.1
12:27:31.341537 {0x00007f6c7d7f6780} [info] server : DependencyVersions.cpp:84 : zlib: 1.2.11
12:27:31.343007 {0x00007f6c7d7f6780} [debug] server : GameRules.cpp:22 : Adding options rules
12:27:31.343287 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_NUM_COMBAT_ROUNDS with default value 3
12:27:31.343406 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_AGGRESSIVE_SHIPS_COMBAT_VISIBLE with default value false
12:27:31.343537 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_DIPLOMACY with default value RULE_DIPLOMACY_ALLOWED_FOR_ALL
12:27:31.343616 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_STOCKPILE_IMPORT_LIMITED with default value false
12:27:31.343690 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_PRODUCTION_QUEUE_FRONTLOAD_FACTOR with default value 0
12:27:31.343806 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_PRODUCTION_QUEUE_TOPPING_UP_FACTOR with default value 0
12:27:31.343864 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_CHEAP_AND_FAST_BUILDING_PRODUCTION with default value false
12:27:31.343947 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_CHEAP_AND_FAST_SHIP_PRODUCTION with default value false
12:27:31.344006 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_SPEED_FACTOR with default value 1
12:27:31.344070 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_STRUCTURE_FACTOR with default value 1
12:27:31.344132 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_WEAPON_DAMAGE_FACTOR with default value 1
12:27:31.344194 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_FIGHTER_DAMAGE_FACTOR with default value 1
12:27:31.344269 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_CHEAP_AND_FAST_TECH_RESEARCH with default value false
12:27:31.344331 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_RESEED_PRNG_SERVER with default value true
12:27:31.344388 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_STARLANES_EVERYWHERE with default value false
12:27:31.344469 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_THRESHOLD_HUMAN_PLAYER_WIN with default value 0
12:27:31.344525 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_ONLY_ALLIANCE_WIN with default value true
12:27:31.344573 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_ALLOW_CONCEDE with default value false
12:27:31.344630 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_CONCEDE_COLONIES_THRESHOLD with default value 1
12:27:31.344687 {0x00007f6c7d7f6780} [debug] server : GameRules.h:125 : Added game rule named RULE_SHOW_DETAILED_EMPIRES_DATA with default value true
12:27:31.357821 {0x00007f6c7d7f6780} [info] log : Logger.cpp:200 : Added logger named "FSM"
12:27:31.357956 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "FSM" logger threshold to "debug".
12:27:31.357987 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "FSM" from optionsDB using threshold debug
12:27:31.358042 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:330 : FreeOrion server waiting for network events
12:27:31.358150 {0x00007f6c7d7f6780} [info] log : Logger.cpp:200 : Added logger named "network"
12:27:31.358211 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "network" logger threshold to "debug".
12:27:31.358238 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "network" from optionsDB using threshold debug
12:27:31.358713 {0x00007f6c7d7f6780} [info] server : i18n.cpp:177 : Global locale: en_GB.UTF-8
12:27:31.361773 {0x00007f6c7d7f6780} [debug] server : i18n.cpp:43 : Detected locale language: en
12:27:31.361863 {0x00007f6c7d7f6780} [debug] server : i18n.cpp:58 : GetDefaultStringTableFileName returning: /usr/share/games/freeorion/default/stringtables/en.txt
12:27:31.361897 {0x00007f6c7d7f6780} [debug] server : i18n.cpp:82 : Stringtable option path:
12:27:31.361924 {0x00007f6c7d7f6780} [debug] server : i18n.cpp:85 : Stringtable option path not specified yet, using default: /usr/share/games/freeorion/default/stringtables/en.txt
12:27:31.899171 {0x00007f6c727fc700} [info] log : Logger.cpp:200 : Added logger named "timer"
12:27:31.899319 {0x00007f6c727fc700} [info] log : Logger.cpp:338 : Setting "timer" logger threshold to "debug".
12:27:31.899349 {0x00007f6c727fc700} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "timer" from optionsDB using threshold debug
12:27:31.899382 {0x00007f6c727fc700} [debug] timer : ScopedTimer.cpp:35 : parse_file "fleets.inf" time: 534 ms
12:27:32.054246 {0x00007f6c717fa700} [warn] server : Parse.cpp:526 : A parser failed while parsing "/usr/share/games/freeorion/default/scripting/empire_statistics/STATISTICS_TEST.focs.txt"
12:27:32.055671 {0x00007f6c717fa700} [debug] timer : ScopedTimer.cpp:35 : parse_file "STATISTICS_TEST.focs.txt" time: 682 ms
12:27:32.074890 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "Categories.inf" time: 717 ms
12:27:32.081239 {0x00007f6c717fa700} [debug] timer : ScopedTimer.cpp:35 : parse_file "COLONIES_COUNT.focs.txt" time: 19.6 ms
12:27:32.093525 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "COL_BANFORO.focs.txt" time: 12.4 ms
12:27:32.100088 {0x00007f6c717fa700} [debug] timer : ScopedTimer.cpp:35 : parse_file "RP_OUTPUT.focs.txt" time: 18.6 ms
12:27:32.113663 {0x00007f6c717fa700} [debug] timer : ScopedTimer.cpp:35 : parse_file "Empire.focs.txt" time: 13.4 ms
12:27:32.115183 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_NEBULA_2.focs.txt" time: 752 ms
12:27:32.117166 {0x00007f6c7cff1700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SPY_CATEGORY.focs.txt" time: 760 ms
12:27:32.119053 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "COL_MISIORLA.focs.txt" time: 25.3 ms
12:27:32.120220 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:48 : Adding Double game rule with name: RULE_SHIP_HULL_COST_FACTOR, desc: RULE_SHIP_HULL_COST_FACTOR_DESC, default: 1, min: 0.1, max: 10
12:27:32.120436 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_HULL_COST_FACTOR with default value 1
12:27:32.120531 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:48 : Adding Double game rule with name: RULE_SHIP_PART_COST_FACTOR, desc: RULE_SHIP_PART_COST_FACTOR_DESC, default: 1, min: 0.1, max: 10
12:27:32.120600 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_PART_COST_FACTOR with default value 1
12:27:32.120832 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:48 : Adding Double game rule with name: RULE_TECH_COST_FACTOR, desc: RULE_TECH_COST_FACTOR_DESC, default: 2, min: 0.1, max: 10
12:27:32.120920 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_TECH_COST_FACTOR with default value 2
12:27:32.120997 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:48 : Adding Double game rule with name: RULE_BUILDING_COST_FACTOR, desc: RULE_BUILDING_COST_FACTOR_DESC, default: 1, min: 0.1, max: 10
12:27:32.121061 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_BUILDING_COST_FACTOR with default value 1
12:27:32.121121 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:28 : Adding Boolean game rule with name: RULE_ENABLE_EXPERIMENTORS, desc: RULE_ENABLE_EXPERIMENTORS_DESC, default: true
12:27:32.128380 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_ENABLE_EXPERIMENTORS with default value true
12:27:32.128492 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:28 : Adding Boolean game rule with name: RULE_ENABLE_SUPER_TESTER, desc: RULE_ENABLE_SUPER_TESTER_DESC, default: false
12:27:32.128545 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_ENABLE_SUPER_TESTER with default value false
12:27:32.128822 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:63 : Adding String game rule with name: RULE_TEST_STRING, desc: RULE_TEST_STRING_DESC, default: "PLAYER", allowed: "AI_PLAYER", "MODERATOR", "OBSERVER", "PLAYER",
12:27:32.128925 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_TEST_STRING with default value PLAYER
12:27:32.129004 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_TINY, desc: RULE_HABITABLE_SIZE_DESC, default: 1, min: 0, max: 999
12:27:32.129071 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_TINY with default value 1
12:27:32.129134 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_SMALL, desc: RULE_HABITABLE_SIZE_DESC, default: 2, min: 0, max: 999
12:27:32.129188 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_SMALL with default value 2
12:27:32.129251 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_MEDIUM, desc: RULE_HABITABLE_SIZE_DESC, default: 3, min: 0, max: 999
12:27:32.130921 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_MEDIUM with default value 3
12:27:32.131071 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_LARGE, desc: RULE_HABITABLE_SIZE_DESC, default: 4, min: 0, max: 999
12:27:32.131131 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_LARGE with default value 4
12:27:32.131198 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_HUGE, desc: RULE_HABITABLE_SIZE_DESC, default: 5, min: 0, max: 999
12:27:32.131251 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_HUGE with default value 5
12:27:32.133590 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_ASTEROIDS, desc: RULE_HABITABLE_SIZE_DESC, default: 3, min: 0, max: 999
12:27:32.133729 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_ASTEROIDS with default value 3
12:27:32.133802 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:37 : Adding Integer game rule with name: RULE_HABITABLE_SIZE_GASGIANT, desc: RULE_HABITABLE_SIZE_DESC, default: 6, min: 0, max: 999
12:27:32.133859 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_HABITABLE_SIZE_GASGIANT with default value 6
12:27:32.133913 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:28 : Adding Boolean game rule with name: RULE_SHIP_PART_BASED_UPKEEP, desc: RULE_SHIP_PART_BASED_UPKEEP_DESC, default: false
12:27:32.133968 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_SHIP_PART_BASED_UPKEEP with default value false
12:27:32.134021 {0x00007f6c78fe9700} [debug] server : GameRulesParser.cpp:28 : Adding Boolean game rule with name: RULE_ENABLE_ALLIED_REPAIR, desc: RULE_ENABLE_ALLIED_REPAIR_DESC, default: false
12:27:32.134071 {0x00007f6c78fe9700} [debug] server : GameRules.h:125 : Added game rule named RULE_ENABLE_ALLIED_REPAIR with default value false
12:27:32.135043 {0x00007f6c78fe9700} [debug] timer : ScopedTimer.cpp:35 : parse_file "game_rules.focs.txt" time: 770 ms
12:27:32.136857 {0x00007f6c717fa700} [debug] timer : ScopedTimer.cpp:35 : parse_file "PP_OUTPUT.focs.txt" time: 21.7 ms
12:27:32.140986 {0x00007f6c72ffd700} [debug] timer : ScopedTimer.cpp:35 : parse_file "buildings.inf" time: 775 ms
12:27:32.142096 {0x00007f6c7cff1700} [debug] timer : ScopedTimer.cpp:35 : parse_file "EXTINCT_SPECIES_TECHS.focs.txt" time: 22.4 ms
12:27:32.164675 {0x00007f6c7cff1700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DESIGN_WINDOW.focs.txt" time: 13.1 ms
12:27:32.167517 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_FIGHTERS_2.focs.txt" time: 42.9 ms
12:27:32.187587 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_NEBULA_1.focs.txt" time: 72.2 ms
12:27:32.210401 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_FIGHTERS_3.focs.txt" time: 42.7 ms
12:27:32.213993 {0x00007f6c7cff1700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ORBITAL_DRYDOCK_REPAIR.focs.txt" time: 18 ms
12:27:32.227526 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_SUBSPACE_RIFT.focs.txt" time: 39.7 ms
12:27:32.229864 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_FIGHTERS_4.focs.txt" time: 19.2 ms
12:27:32.242397 {0x00007f6c79feb700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SD_AST_1.focs.txt" time: 892 ms
12:27:32.247923 {0x00007f6c7cff1700} [debug] timer : ScopedTimer.cpp:35 : parse_file "PC_BOMBARD.focs.txt" time: 12.1 ms
12:27:32.256641 {0x00007f6c797ea700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SM_DRAGON.focs.txt" time: 909 ms
12:27:32.258976 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_FIGHTERS_1.focs.txt" time: 28.9 ms
12:27:32.260330 {0x00007f6c79feb700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SD_GRAVITATING2.focs.txt" time: 16.6 ms
12:27:32.280523 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_ION_STORM.focs.txt" time: 52.8 ms
12:27:32.288022 {0x00007f6c79feb700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SD_OUTPOST_BASE.focs.txt" time: 24.3 ms
12:27:32.293459 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_ACCRETION_DISC.focs.txt" time: 12.7 ms
12:27:32.296085 {0x00007f6c797ea700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SM_CLOUD.focs.txt" time: 36.3 ms
12:27:32.300962 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_VOID_SHADOW.focs.txt" time: 33.4 ms
12:27:32.310638 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_BASIC_LARGE.focs.txt" time: 879 ms
12:27:32.312146 {0x00007f6c79feb700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SD_ROBOTIC_OUTPOST_HULL.focs.txt" time: 20.3 ms
12:27:32.324740 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_BIOTERM.focs.txt" time: 23.6 ms
12:27:32.340181 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_EMP.focs.txt" time: 15.2 ms
12:27:32.342879 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CONC_CAMP_MASTER.focs.txt" time: 801 ms
12:27:32.344178 {0x00007f6c7c7f0700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FLD_MOLECULAR_CLOUD.focs.txt" time: 50.5 ms
12:27:32.354548 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_DARK_RAY.focs.txt" time: 11 ms
12:27:32.357920 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_RAMSCOOP.focs.txt" time: 1003 ms
12:27:32.363977 {0x00007f6c71ffb700} [debug] timer : ScopedTimer.cpp:35 : parse_file "monster_fleets.inf" time: 809 ms
12:27:32.368435 {0x00007f6c737fe700} [debug] timer : ScopedTimer.cpp:35 : parse_file "items.inf" time: 911 ms
12:27:32.374839 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_GRV.focs.txt" time: 20.1 ms
12:27:32.381068 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CONC_CAMP_SLAVE.focs.txt" time: 38 ms
12:27:32.393527 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SUPERNOVA.focs.txt" time: 12.3 ms
12:27:32.397123 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_BASIC_TANK.focs.txt" time: 39 ms
12:27:32.399296 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_CHAOS_WAVE.focs.txt" time: 17.9 ms
12:27:32.408425 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_ENERGY_FRIGATE.focs.txt" time: 97.6 ms
12:27:32.412578 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "NOVA_BOMB_ACTIVATOR.focs.txt" time: 18.9 ms
12:27:32.419187 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:533 : Reading player save game data from: /home/un/.local/share/freeorion/save/save-20220617_114505.sav
12:27:32.419391 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:555 : Attempting XML deserialization...
12:27:32.424353 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_ZERO_FUEL.focs.txt" time: 27 ms
12:27:32.435902 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:565 : Done reading player save game data...
12:27:32.436043 {0x00007f6c7d7f6780} [debug] timer : ScopedTimer.cpp:35 : LoadPlayerSaveHeaderData: /home/un/.local/share/freeorion/save/save-20220617_114505.sav time: 16.8 ms
12:27:32.436152 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:533 : Reading player save game data from: /home/un/.local/share/freeorion/save/save-20220617_114505.sav
12:27:32.436255 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:555 : Attempting XML deserialization...
12:27:32.436586 {0x00007f6c7d7f6780} [debug] server : SaveLoad.cpp:565 : Done reading player save game data...
12:27:32.436665 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:199 : ServerApp::CreateAIClients: 10 player (maybe not all AIs) at max aggression: 5
12:27:32.436766 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:356 : ServerApp::CleanupAIs() telling AIs game is ending
12:27:32.436798 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:376 : ServerApp::CleanupAIs() killing 0 AI clients.
12:27:32.436852 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:259 : starting AIs with /usr/lib/freeorion/freeorionca
12:27:32.436879 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:260 : ai-aggression set to 5
12:27:32.436907 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:261 : ai-path set to 'python/AI'
12:27:32.436936 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:268 : ai-config not set.
12:27:32.442974 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DERELICT_3.focs.txt" time: 21.5 ms
12:27:32.445831 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_2_2.focs.txt" time: 43.1 ms
12:27:32.455742 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_TRANSPATIAL_DRIVE.focs.txt" time: 31.1 ms
12:27:32.455921 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_6
12:27:32.476265 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_SOLAR.focs.txt" time: 67.6 ms
12:27:32.479128 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_9
12:27:32.483822 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ACCRETION_DISC.focs.txt" time: 22.7 ms
12:27:32.489616 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_4_2.focs.txt" time: 43.5 ms
12:27:32.500230 {0x00007f6c7b7ee700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SP_GEORGE.focs.txt" time: 1146 ms
12:27:32.511858 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_1
12:27:32.514803 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "PANOPTICON.focs.txt" time: 30.6 ms
12:27:32.531468 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_IMPROVED_ENGINE_COUPLINGS.focs.txt" time: 65.3 ms
12:27:32.538790 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_4
12:27:32.567962 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_5
12:27:32.581394 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_1_2.focs.txt" time: 91.5 ms
12:27:32.590253 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "GAIA.focs.txt" time: 75.2 ms
12:27:32.571692 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_2
12:27:32.600929 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_QUANTUM_ENERGY.focs.txt" time: 124 ms
12:27:32.610070 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_SINGULARITY_ENGINE_CORE.focs.txt" time: 78.4 ms
12:27:32.614125 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "COMPUTRONIUM.focs.txt" time: 23.7 ms
12:27:32.630476 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_3_1.focs.txt" time: 48.9 ms
12:27:32.660923 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "EXTINCT_MISIORLA.focs.txt" time: 46.5 ms
12:27:32.663867 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_7
12:27:32.684065 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_9
12:27:32.714076 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "HIGH_TECH_NATIVES.focs.txt" time: 52.7 ms
12:27:32.720262 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_1_3.focs.txt" time: 89.6 ms
12:27:32.723089 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FU_N_DIMENSIONAL_ENGINE_MATRIX.focs.txt" time: 112 ms
12:27:32.727887 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:286 : done starting AI AI_3
12:27:32.728371 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:39 : Initializing FreeOrion Python interface
12:27:32.728574 {0x00007f6c7d7f6780} [debug] server : ServerFramework.cpp:60 : Initializing server Python imports
12:27:32.739639 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CONC_CAMP.focs.txt" time: 1161 ms
12:27:32.750871 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ART_PLANET.focs.txt" time: 11 ms
12:27:32.755409 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_SOLAR_CONNECTION.focs.txt" time: 34.9 ms
12:27:32.758202 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "EXTINCT_BANFORO.focs.txt" time: 43.8 ms
12:27:32.779564 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "TRANSFORMER.focs.txt" time: 28.5 ms
12:27:32.781900 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SCRYING_SPHERE.focs.txt" time: 23.5 ms
12:27:32.784123 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_STD_PLATE.focs.txt" time: 60.8 ms
12:27:32.786710 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_4_1.focs.txt" time: 22.6 ms
12:27:32.793384 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CULTURE_LIBRARY.focs.txt" time: 13.5 ms
12:27:32.794508 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_FRACTAL_ENERGY.focs.txt" time: 193 ms
12:27:32.804959 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_3_4.focs.txt" time: 18 ms
12:27:32.809858 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "NEUTRONIUM_EXTRACTOR.focs.txt" time: 16.3 ms
12:27:32.823565 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_3_2.focs.txt" time: 18.4 ms
12:27:32.825165 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "HONEYCOMB.focs.txt" time: 43.1 ms
12:27:32.828047 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_NEUTRONIUM_PLATE.focs.txt" time: 43.7 ms
12:27:32.843719 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_2_3.focs.txt" time: 20 ms
12:27:32.845578 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ORGANIC_XENO_FAC.focs.txt" time: 26.5 ms
12:27:32.848071 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "KRAKEN_IN_THE_ICE.focs.txt" time: 22.7 ms
12:27:32.854932 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_ZORTRIUM_PLATE.focs.txt" time: 26.7 ms
12:27:32.859891 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ORGANIC_CEL_GRO.focs.txt" time: 14.1 ms
12:27:32.865619 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_COMPRESSED_ENERGY.focs.txt" time: 70.9 ms
12:27:32.878063 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_XENTRONIUM_PLATE.focs.txt" time: 22.9 ms
12:27:32.879365 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_ARC_DISRUPTOR.focs.txt" time: 35.4 ms
12:27:32.885466 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "BASE.focs.txt" time: 25.4 ms
12:27:32.897320 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ANCIENT_RUINS.focs.txt" time: 49.1 ms
12:27:32.899395 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_4_4.focs.txt" time: 19.8 ms
12:27:32.902551 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ASTEROID.focs.txt" time: 16.9 ms
12:27:32.907438 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_PRECURSOR_PLATE.focs.txt" time: 29.2 ms
12:27:32.913588 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "RESONANT_MOON.focs.txt" time: 16.1 ms
12:27:32.917668 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_2_1.focs.txt" time: 18.1 ms
12:27:32.930222 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "MODERATE_TECH_NATIVES.focs.txt" time: 16.4 ms
12:27:32.942240 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:66 : Python initialized
12:27:32.942346 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:67 : Python version: 3.9.2 (default, Feb 28 2021, 17:03:44)
[GCC 10.2.1 20210110]
12:27:32.942380 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_BASIC_MEDIUM.focs.txt" time: 76.6 ms
12:27:32.942391 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:68 : Python prefix: /usr
12:27:32.942463 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:69 : Python module search path: /usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload
12:27:32.942524 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:76 : Initializing C++ interfaces for Python
12:27:32.944853 {0x00007f6c7d7f6780} [debug] server : ServerFramework.cpp:66 : Initializing server Python modules
12:27:32.957440 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "POSITRONIUM.focs.txt" time: 27 ms
12:27:32.960994 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_ROCK_PLATE.focs.txt" time: 53.3 ms
12:27:32.975330 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_3_3.focs.txt" time: 57.5 ms
12:27:32.975359 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_DIAMOND_PLATE.focs.txt" time: 14.2 ms
12:27:32.987830 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ORBITAL_DRYDOCK.focs.txt" time: 85 ms
12:27:32.996866 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FRUIT.focs.txt" time: 39.2 ms
12:27:33.010912 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "AR_CRYSTAL_PLATE.focs.txt" time: 25 ms
12:27:33.014637 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ENERGY_COMP.focs.txt" time: 26.6 ms
12:27:33.016795 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_2_4.focs.txt" time: 41.2 ms
12:27:33.025137 {0x00007f6c7b7ee700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SP_HUMAN.focs.txt" time: 524 ms
12:27:33.028655 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_MINIASTEROID_SWARM.focs.txt" time: 86.1 ms
12:27:33.032585 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CRYSTALS.focs.txt" time: 35.5 ms
12:27:33.037519 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_SPINAL_ANTIMATTER.focs.txt" time: 11.6 ms
12:27:33.044287 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ENERGY_SOLAR.focs.txt" time: 29.4 ms
12:27:33.056530 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_1.focs.txt" time: 45.4 ms
12:27:33.057650 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CON_NANOROBO.focs.txt" time: 13.2 ms
12:27:33.062352 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ELERIUM.focs.txt" time: 29.6 ms
12:27:33.064826 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_WEAPON_1_4.focs.txt" time: 27.1 ms
12:27:33.085716 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "MINERALS.focs.txt" time: 23.2 ms
12:27:33.087739 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ASTEROID_REF.focs.txt" time: 29.9 ms
12:27:33.091139 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_3.focs.txt" time: 34.4 ms
12:27:33.099471 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_CRYSTALLIZED_ASTEROID.focs.txt" time: 70.5 ms
12:27:33.109621 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SHP_ROOT_AGGRESSION.focs.txt" time: 41.4 ms
12:27:33.114176 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CON_GEOINT.focs.txt" time: 26.2 ms
12:27:33.119156 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "PROBIOTIC.focs.txt" time: 33.2 ms
12:27:33.128420 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_0.focs.txt" time: 37 ms
12:27:33.129804 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "INDUSTRY_CENTER_II.focs.txt" time: 20 ms
12:27:33.140142 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ORGANIC_ORB_INC.focs.txt" time: 25.8 ms
12:27:33.150238 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SUPERCONDUCTOR.focs.txt" time: 30.9 ms
12:27:33.161132 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_ASTEROID.focs.txt" time: 61.5 ms
12:27:33.162588 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CON_ADV_ENGINE.focs.txt" time: 22.2 ms
12:27:33.163595 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "MONOPOLE.focs.txt" time: 13.2 ms
12:27:33.169206 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_4.focs.txt" time: 40.6 ms
12:27:33.184162 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "INTERSTELLAR_ENTANGLEMENT_FACTORY.focs.txt" time: 47 ms
12:27:33.190982 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_KRILL.focs.txt" time: 21.6 ms
12:27:33.191916 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SCRYING_SPHERE.focs.txt" time: 25.5 ms
12:27:33.202744 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SPICE.focs.txt" time: 39 ms
12:27:33.212761 {0x00007f6c7d7f6780} [info] log : Logger.cpp:200 : Added logger named "python"
12:27:33.212944 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "python" logger threshold to "debug".
12:27:33.212978 {0x00007f6c7d7f6780} [debug] log : LoggerWithOptionsDB.cpp:105 : Configure log source "python" from optionsDB using threshold debug
12:27:33.213014 {0x00007f6c7d7f6780} [debug] python : configure_logging.py:203 : Python stdout and stderr are redirected to ai process.
12:27:33.213676 {0x00007f6c7d7f6780} [info] python : configure_logging.py:223 : The python logger is initialized with a log level of DEBUG
12:27:33.217311 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ANCIENT_RUINS_DEPLETED.focs.txt" time: 14.4 ms
12:27:33.222611 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ADAPTIVE_AUTOMATION.focs.txt" time: 38.2 ms
12:27:33.225984 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "FT_HANGAR_2.focs.txt" time: 34.8 ms
12:27:33.230491 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "GAIA_TRANS.focs.txt" time: 38.4 ms
12:27:33.237568 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "VOLCANIC_ASH_SLAVE.focs.txt" time: 20.1 ms
12:27:33.248314 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CO_COL_OUTPOST.focs.txt" time: 22.1 ms
12:27:33.255002 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DIM_RIFT_MASTER.focs.txt" time: 17.2 ms
12:27:33.257014 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SINGULAR_GEN.focs.txt" time: 29.4 ms
12:27:33.264356 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_SMALL_CAMOUFLAGE_ASTEROID.focs.txt" time: 103 ms
12:27:33.268654 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CO_COLONY_POD.focs.txt" time: 20.1 ms
12:27:33.276662 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SOLAR_ORB_GEN.focs.txt" time: 19.4 ms
12:27:33.280505 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CLOUD_COVER_SLAVE.focs.txt" time: 25.2 ms
12:27:33.282916 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "COLONY_BASE.focs.txt" time: 40.9 ms
12:27:33.285240 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CO_SUPEND_ANIM_POD.focs.txt" time: 16.4 ms
12:27:33.293800 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "VOID_SLAVE.focs.txt" time: 13.1 ms
12:27:33.297994 {0x00007f6c7d7f6780} [warn] python : auth.py:36 : Cann't read auth file /home/un/.config/freeorion/auth.txt: <class 'FileNotFoundError'> [Errno 2] No such file or directory: '/home/un/.config/freeorion/auth.txt'
12:27:33.298222 {0x00007f6c7d7f6780} [info] python : auth.py:41 : Auth initialized
12:27:33.300016 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "BLACK_HOLE_COLLAPSER.focs.txt" time: 16.9 ms
12:27:33.310133 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "VOID_MASTER.focs.txt" time: 16.1 ms
12:27:33.314920 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DT_DETECTOR_1.focs.txt" time: 29.5 ms
12:27:33.322481 {0x00007f6c7d7f6780} [info] python : chat.py:14 : Chat initialized
12:27:33.322635 {0x00007f6c7d7f6780} [debug] server : ServerFramework.cpp:111 : Server Python modules successfully initialized!
12:27:33.322670 {0x00007f6c7d7f6780} [debug] server : CommonFramework.cpp:112 : FreeOrion Python interface successfully initialized!
12:27:33.323076 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_HEAVY_ASTEROID.focs.txt" time: 58.5 ms
12:27:33.332028 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "VOID_PREDICTION.focs.txt" time: 40.5 ms
12:27:33.336887 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DIM_RIFT_SLAVE.focs.txt" time: 26.5 ms
12:27:33.342211 {0x00007f6c7b7ee700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SP_LAENFA.focs.txt" time: 316 ms
12:27:33.344382 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "GATEWAY_VOID.focs.txt" time: 44.2 ms
12:27:33.346686 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DT_DETECTOR_4.focs.txt" time: 31.5 ms
12:27:33.347316 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "CLOUD_COVER_MASTER.focs.txt" time: 10.2 ms
12:27:33.356147 {0x00007f6c7d7f6780} [debug] server : ServerApp.cpp:456 : Handling logging config message from the host.
12:27:33.356497 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "ai" logger threshold to "debug".
12:27:33.356554 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "client" logger threshold to "debug".
12:27:33.356599 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "server" logger threshold to "debug".
12:27:33.356638 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "FSM" logger threshold to "debug".
12:27:33.356677 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "IDallocator" logger threshold to "debug".
12:27:33.356714 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "combat" logger threshold to "debug".
12:27:33.356751 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "combat_log" logger threshold to "debug".
12:27:33.356788 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "conditions" logger threshold to "debug".
12:27:33.356826 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "effects" logger threshold to "debug".
12:27:33.356866 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "log" logger threshold to "debug".
12:27:33.356904 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "network" logger threshold to "debug".
12:27:33.356944 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "python" logger threshold to "debug".
12:27:33.356982 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "supply" logger threshold to "debug".
12:27:33.357021 {0x00007f6c7d7f6780} [info] log : Logger.cpp:338 : Setting "timer" logger threshold to "debug".
12:27:33.366468 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "NEUTRONIUM_FORGE.focs.txt" time: 21.9 ms
12:27:33.367257 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "HEAD_ON_A_SPIKE.focs.txt" time: 16.8 ms
12:27:33.370976 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "GENERIC_SUPPLIES.focs.txt" time: 34.8 ms
12:27:33.371371 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DT_DETECTOR_2.focs.txt" time: 24.5 ms
12:27:33.390841 {0x00007f6c7d7f2700} [debug] timer : ScopedTimer.cpp:35 : parse_file "GENOME_BANK.focs.txt" time: 24.2 ms
12:27:33.396224 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "DT_DETECTOR_3.focs.txt" time: 24.7 ms
12:27:33.399932 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ZERO_GEN.focs.txt" time: 28.7 ms
12:27:33.411407 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "PHILOSOPHER.focs.txt" time: 33.2 ms
12:27:33.412133 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_CAMOUFLAGE_ASTEROID.focs.txt" time: 88.8 ms
12:27:33.437367 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "WORLDTREE.focs.txt" time: 25.7 ms
12:27:33.441495 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SR_PLASMA_DISCHARGE.focs.txt" time: 45.1 ms
12:27:33.449075 {0x00007f6c73fff700} [debug] timer : ScopedTimer.cpp:35 : parse_file "EXOBOTS.focs.txt" time: 20.3 ms
12:27:33.460350 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "TEMPORAL_ANOMOLY.focs.txt" time: 22.8 ms
12:27:33.470454 {0x00007f6c7afed700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SR_SPINAL_ANTIMATTER.focs.txt" time: 28.8 ms
12:27:33.473224 {0x00007f6c7bfef700} [debug] timer : ScopedTimer.cpp:35 : parse_file "ABANDONED_COLONY.focs.txt" time: 12.7 ms
12:27:33.474083 {0x00007f6c7a7ec700} [debug] timer : ScopedTimer.cpp:35 : parse_file "SH_SMALL_ASTEROID.focs.txt" time: 61.7 ms

User avatar
LienRag
Cosmic Dragon
Posts: 2148
Joined: Fri May 17, 2019 5:03 pm

Re: Server not accepting players

#5 Post by LienRag »

You can zip the log and post the zip files.

User avatar
LienRag
Cosmic Dragon
Posts: 2148
Joined: Fri May 17, 2019 5:03 pm

Re: Server not accepting players

#6 Post by LienRag »

But IIRC, I had the same problem when the save game got corrupted.

Yithazr
Space Krill
Posts: 4
Joined: Fri Jun 17, 2022 11:46 am

Re: Server not accepting players

#7 Post by Yithazr »

LienRag wrote: Fri Jun 17, 2022 3:59 pm You can zip the log and post the zip files.
Zip files are binaries. If I put one in the body of a message at best the forum software would reject it and at worst it would look horrible and get corrupted. I will try attaching a zip to see if attaching failed because of file sizes. If this works then there is a problem with the forum software silently rejecting large files.

The problem I was experiencing with FreeOrion 0.4.10.1 was that sometimes when it fails to read a saved game file it gives a misleading error message.

[ Found the problem with the previous attachments: The zip file had a green tick under status in the attachments box. I retried with the original logs and the status was a yellow triangle with an exclamation mark. The forum software was not failing silently, but it was a cryptic whisper. ]
Attachments
logs.zip
(25.26 KiB) Downloaded 62 times

Post Reply