Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

forever.log growing indefinitely until disk full #187

Closed
Crotalus opened this issue Mar 24, 2015 · 8 comments
Closed

forever.log growing indefinitely until disk full #187

Crotalus opened this issue Mar 24, 2015 · 8 comments
Labels
bug

Comments

@Crotalus
Copy link
Member

Log is filled with this error

2015-03-24 10:34:20,128 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
@Crotalus
Copy link
Member Author

Lines before the error starts to spam:

2015-03-23 23:27:55,832 INFO     faf.secondaryServer.Statistic Instanciating secondary server.
2015-03-23 23:27:55,842 INFO     faf.util             Read common stylesheet: client/client.css
2015-03-23 23:27:55,930 INFO     faf.util             Read common stylesheet: stats/formatters/style.css
2015-03-23 23:27:55,946 INFO     faf.secondaryServer.Tournament Instanciating secondary server.
2015-03-23 23:27:55,946 INFO     faf.util             Read common stylesheet: tournaments/formatters/style.css
2015-03-23 23:27:55,947 DEBUG    faf.vault            Map Vault tab instantiating
2015-03-23 23:27:56,977 DEBUG    faf.modvault         Mod Vault tab instantiating
2015-03-23 23:27:57,000 DEBUG    faf.modvault         getting installed mods. Count: 18
2015-03-23 23:27:57,013 INFO     faf.replays          Replays Widget instantiated.
2015-03-23 23:27:57,015 INFO     faf.tutorials        Tutorials instantiated.
2015-03-23 23:27:57,023 INFO     faf.util             Read common stylesheet: coop/formatters/style.css
2015-03-23 23:27:57,049 INFO     faf.fa.replayserver  listening on address 127.0.0.1:15000
2015-03-23 23:27:57,051 INFO     faf.fa.relayserver   relay listening on address 127.0.0.1:50008
2015-03-23 23:27:57,115 INFO     faf.client           Outgoing JSON Message: {"command": "ask_session"}
2015-03-23 23:27:59,236 INFO     faf.client           Attempting to login as: Crotalus
2015-03-23 23:27:59,237 INFO     faf.client           Outgoing JSON Message: login.
2015-03-23 23:27:59,434 INFO     faf.client           Login accepted.
2015-03-23 23:27:59,436 INFO     faf.fa.upnp          Creating UPnP port mappings...
2015-03-23 23:27:59,713 INFO     faf.fa.upnp          -> UDP mapping of 192.168.10.112:6112 to 213.21.73.38:6112
2015-03-23 23:27:59,721 INFO     faf.client           The game port is now set to 6112
2015-03-23 23:27:59,721 INFO     faf.client           sending packet to lobby.faforever.com
2015-03-23 23:28:01,269 DEBUG    faf.downloader       Searching map preview for: fortress teamplay_ai
2015-03-23 23:31:05,142 DEBUG    faf.downloader       Searching map preview for: desert arena.v0001
2015-03-23 23:34:55,042 DEBUG    faf.downloader       Searching map preview for: 6vs6 battle of thermopylae v5
2015-03-23 23:35:05,046 DEBUG    faf.downloader       Searching map preview for: voodoo planet v2b
2015-03-23 23:35:35,049 DEBUG    faf.downloader       Searching map preview for: scmp_018
2015-03-23 23:36:25,059 DEBUG    faf.downloader       Searching map preview for: temples teamplay_ai
2015-03-23 23:41:25,069 DEBUG    faf.downloader       Searching map preview for: 6v6 team tournament map two
2015-03-23 23:42:55,055 DEBUG    faf.downloader       Searching map preview for: 5vs5 battle of thermopylae v2
2015-03-23 23:55:01,957 DEBUG    faf.downloader       Searching map preview for: flat 512
2015-03-23 23:56:01,881 DEBUG    faf.downloader       Searching map preview for: gap of genesis teamplay_ai
2015-03-23 23:56:41,888 DEBUG    faf.downloader       Searching map preview for: x1mp_008
2015-03-23 23:57:01,907 DEBUG    faf.downloader       Searching map preview for: drakes_ravine_4v4_v3
2015-03-23 23:57:11,901 DEBUG    faf.downloader       Searching map preview for: gap of genesis
2015-03-23 23:58:06,905 DEBUG    faf.downloader       Searching map preview for: tt open palms.v0001
2015-03-24 00:01:36,871 DEBUG    faf.downloader       Searching map preview for: scmp_haz09fa.v0001
2015-03-24 00:03:47,293 DEBUG    faf.downloader       Searching map preview for: bmbattlegroundv11
2015-03-24 00:03:57,276 DEBUG    faf.downloader       Searching map preview for: flat
2015-03-24 00:04:52,305 DEBUG    faf.downloader       Searching map preview for: sulphur mounds.v0002
2015-03-24 00:04:52,309 DEBUG    faf.downloader       Searching map preview for: canis 5v5 spezial edition
2015-03-24 00:05:47,308 DEBUG    faf.downloader       Searching map preview for: gap of rhonda teamplay.v0006
2015-03-24 00:06:57,279 DEBUG    faf.downloader       Searching map preview for: summer duel.v0002
2015-03-24 00:07:22,956 DEBUG    faf.downloader       Searching map preview for: malazgirt.v0003
2015-03-24 00:07:32,937 DEBUG    faf.downloader       Searching map preview for: 5iver survival land naval.v0001
2015-03-24 00:08:37,905 DEBUG    faf.downloader       Searching map preview for: the drunken pirates dance teamplayai
2015-03-24 00:09:42,914 DEBUG    faf.downloader       Searching map preview for: crimson_feud
2015-03-24 00:13:48,424 DEBUG    faf.downloader       Searching map preview for: scmp_033
2015-03-24 00:14:33,420 DEBUG    faf.downloader       Searching map preview for: mountain pass
2015-03-24 00:16:34,289 DEBUG    faf.downloader       Searching map preview for: world2domination.v0001
2015-03-24 00:17:54,348 DEBUG    faf.downloader       Searching map preview for: last oasis.v0002
2015-03-24 00:19:39,319 DEBUG    faf.downloader       Searching map preview for: scmp_di_509.v0001
2015-03-24 00:20:14,286 DEBUG    faf.downloader       Searching map preview for: monkey playland.v0001
2015-03-24 00:21:49,278 DEBUG    faf.downloader       Searching map preview for: chokequest.v0006
2015-03-24 00:23:04,318 DEBUG    faf.downloader       Searching map preview for: water world.v0001
2015-03-24 00:24:39,285 DEBUG    faf.downloader       Searching map preview for: coastal bridge v2
2015-03-24 00:25:09,285 DEBUG    faf.downloader       Searching map preview for: fight of the giants v3.v0001
2015-03-24 00:25:59,285 DEBUG    faf.downloader       Searching map preview for: tkp_isis_6v6
2015-03-24 00:26:54,289 DEBUG    faf.downloader       Searching map preview for: gap_of_rohan_island.v0004
2015-03-24 00:30:44,372 DEBUG    faf.downloader       Searching map preview for: cauldron_desert.v0001
2015-03-24 00:32:04,286 DEBUG    faf.downloader       Searching map preview for: thermopylae v3.v0003
2015-03-24 00:38:54,627 DEBUG    faf.downloader       Searching map preview for: slaughter pass.v0001
2015-03-24 00:41:04,637 DEBUG    faf.downloader       Searching map preview for: hilly_plateau_small.v0001
2015-03-24 00:42:54,630 DEBUG    faf.downloader       Searching map preview for: kazam
2015-03-24 00:43:59,630 DEBUG    faf.downloader       Searching map preview for: diversity.v0008
2015-03-24 00:47:39,642 DEBUG    faf.downloader       Searching map preview for: wonder 5v5
2015-03-24 00:52:29,634 DEBUG    faf.downloader       Searching map preview for: scca_coop_e03_v03
2015-03-24 00:53:19,631 DEBUG    faf.downloader       Searching map preview for: souvenir.v0002
2015-03-24 00:54:19,693 DEBUG    faf.downloader       Searching map preview for: loki.v0001
2015-03-24 00:54:44,638 DEBUG    faf.downloader       Searching map preview for: bridge two far.v0003
2015-03-24 00:57:59,640 DEBUG    faf.downloader       Searching map preview for: x1mp_012
2015-03-24 00:59:54,642 DEBUG    faf.downloader       Searching map preview for: hilly jungle (the third)
2015-03-24 01:02:34,627 DEBUG    faf.downloader       Searching map preview for: voodoo mountains v6
2015-03-24 01:04:34,628 DEBUG    faf.downloader       Searching map preview for: scmp_027
2015-03-24 01:10:56,598 DEBUG    faf.downloader       Searching map preview for: scmp_0130.v0001
2015-03-24 01:12:36,585 DEBUG    faf.downloader       Searching map preview for: fields of isis 3v3
2015-03-24 01:14:31,581 DEBUG    faf.downloader       Searching map preview for: cooperative war.v0001
2015-03-24 01:16:33,030 DEBUG    faf.downloader       Searching map preview for: scmp_035
2015-03-24 01:17:18,012 DEBUG    faf.downloader       Searching map preview for: havenofwar
2015-03-24 01:18:43,016 DEBUG    faf.downloader       Searching map preview for: survival_escape_v3ga.v0001
2015-03-24 01:19:33,009 DEBUG    faf.downloader       Searching map preview for: noobmap teamplay
2015-03-24 01:24:23,154 DEBUG    faf.downloader       Searching map preview for: smokers teamplay_ai
2015-03-24 01:25:23,188 DEBUG    faf.downloader       Searching map preview for: fredouz battle 2.v0001
2015-03-24 01:26:53,183 DEBUG    faf.downloader       Searching map preview for: waters of ozone.v0002
2015-03-24 01:28:28,161 DEBUG    faf.downloader       Searching map preview for: desert siege
2015-03-24 01:35:48,561 DEBUG    faf.downloader       Searching map preview for: bunnyroadnokev1.v0001
2015-03-24 01:39:53,582 DEBUG    faf.downloader       Searching map preview for: phantom_sung_island.v0003
2015-03-24 01:39:58,555 DEBUG    faf.downloader       Searching map preview for: 10 the plain
2015-03-24 01:45:18,562 DEBUG    faf.downloader       Searching map preview for: grave passages
2015-03-24 01:46:08,562 DEBUG    faf.downloader       Searching map preview for: radiator.v0001
2015-03-24 01:46:08,585 DEBUG    faf.downloader       Searching map preview for: scca_coop_a02_v03
2015-03-24 01:46:28,555 DEBUG    faf.downloader       Searching map preview for: forbidden passv4
2015-03-24 01:47:33,592 DEBUG    faf.downloader       Searching map preview for: planetzorg.v0002
2015-03-24 01:51:38,572 DEBUG    faf.downloader       Searching map preview for: the passing.v0001
2015-03-24 02:05:24,108 DEBUG    faf.downloader       Searching map preview for: x1mp_009
2015-03-24 02:06:19,101 DEBUG    faf.downloader       Searching map preview for: scmp_030
2015-03-24 02:07:09,102 DEBUG    faf.downloader       Searching map preview for: mururoa v2
2015-03-24 02:07:19,102 DEBUG    faf.downloader       Searching map preview for: six leaf clover3.v0001
2015-03-24 02:07:24,118 DEBUG    faf.downloader       Searching map preview for: comet catcher.v0001
2015-03-24 02:08:19,101 DEBUG    faf.downloader       Searching map preview for: mako teamplay_ai
2015-03-24 02:14:59,188 DEBUG    faf.downloader       Searching map preview for: 3v3 croesus 13.v0001
2015-03-24 02:29:29,637 DEBUG    faf.downloader       Searching map preview for: the drunken pirates dance
2015-03-24 02:34:39,650 DEBUG    faf.downloader       Searching map preview for: 4v4isis.v0001
2015-03-24 02:36:59,634 DEBUG    faf.downloader       Searching map preview for: 2v4 bad cage 10x10 v1
2015-03-24 02:38:34,630 DEBUG    faf.downloader       Searching map preview for: the longest way.v0004
2015-03-24 02:42:00,726 INFO     faf.fa               fa.exe.replay(PyQt4.QtCore.QUrl(u'faflive://lobby.faforever.com/3
241275/Myronovandis.SCFAreplay?map=x1ca_coop_001_v07&mod=coop'), detach = False
2015-03-24 02:42:00,727 INFO     faf.fa               Checking FA for: coop and map x1ca_coop_001_v07
2015-03-24 02:42:00,727 INFO     faf.fa               Updating FA for mod: coop, version None
2015-03-24 02:42:00,727 DEBUG    faf.updater          Update started at 2015-03-24 02:42:00
2015-03-24 02:42:00,727 DEBUG    faf.updater          Using game path: C:\Program Files (x86)\THQ\Gas Powered Games\Sup
reme Commander - Forged Alliance
2015-03-24 02:42:00,729 DEBUG    faf.updater          Using appdata: C:\ProgramData\FAForever
2015-03-24 02:42:00,846 DEBUG    faf.updater          Connected to update server at 2015-03-24 02:42:00
2015-03-24 02:42:00,854 DEBUG    faf.updater          writeToServer(GET_FILES_TO_UPDATE, [FAF])
2015-03-24 02:42:00,895 DEBUG    faf.updater          handleAction(LIST_FILES_TO_UP) - 324 bytes
2015-03-24 02:42:00,895 DEBUG    faf.updater          Files to update: [ForgedAlliance.exe, init_faf.lua, init_ladder1v
1.lua, GDFBinary.dll, SupComDataPath.lua, SupComDataPathFAF.lua, init_nonxt.lua]
2015-03-24 02:42:00,898 DEBUG    faf.updater          writeToServer(UPDATE, [bin, init_nonxt.lua, 16da7063ae3309c154a2c
544f559b3a9])
2015-03-24 02:42:00,898 DEBUG    faf.updater          writeToServer(UPDATE, [bin, GDFBinary.dll, 6f57f59c2e705d7a5a02d4
9e3915cb26])
2015-03-24 02:42:00,900 DEBUG    faf.updater          writeToServer(UPDATE, [bin, init_faf.lua, f7e666f342d17e3829fc19d
bc140e4c5])
2015-03-24 02:42:00,920 DEBUG    faf.updater          writeToServer(UPDATE, [bin, ForgedAlliance.exe, dcf82138bb46628d9
9861c64d21407b7])
2015-03-24 02:42:00,921 DEBUG    faf.updater          writeToServer(UPDATE, [bin, SupComDataPath.lua, 55ac1ef97845f2291
da64b830299331e])
2015-03-24 02:42:00,921 DEBUG    faf.updater          writeToServer(UPDATE, [bin, SupComDataPathFAF.lua, 962c9d1327657d
77184adcf358c628f1])
2015-03-24 02:42:00,923 DEBUG    faf.updater          writeToServer(UPDATE, [bin, init_ladder1v1.lua, ea96ac9cec88af29c
08556c96465acfe])
2015-03-24 02:42:00,964 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 56 bytes
2015-03-24 02:42:00,964 DEBUG    faf.updater          file : init_nonxt.lua
2015-03-24 02:42:00,964 DEBUG    faf.updater          init_nonxt.lua is up to date.
2015-03-24 02:42:01,015 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 54 bytes
2015-03-24 02:42:01,015 DEBUG    faf.updater          file : GDFBinary.dll
2015-03-24 02:42:01,015 DEBUG    faf.updater          GDFBinary.dll is up to date.
2015-03-24 02:42:01,016 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 52 bytes
2015-03-24 02:42:01,016 DEBUG    faf.updater          file : init_faf.lua
2015-03-24 02:42:01,016 DEBUG    faf.updater          init_faf.lua is up to date.
2015-03-24 02:42:01,016 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 64 bytes
2015-03-24 02:42:01,016 DEBUG    faf.updater          file : ForgedAlliance.exe
2015-03-24 02:42:01,016 DEBUG    faf.updater          ForgedAlliance.exe is up to date.
2015-03-24 02:42:01,016 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 64 bytes
2015-03-24 02:42:01,016 DEBUG    faf.updater          file : SupComDataPath.lua
2015-03-24 02:42:01,016 DEBUG    faf.updater          SupComDataPath.lua is up to date.
2015-03-24 02:42:01,016 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 70 bytes
2015-03-24 02:42:01,016 DEBUG    faf.updater          file : SupComDataPathFAF.lua
2015-03-24 02:42:01,016 DEBUG    faf.updater          SupComDataPathFAF.lua is up to date.
2015-03-24 02:42:01,017 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 64 bytes
2015-03-24 02:42:01,017 DEBUG    faf.updater          file : init_ladder1v1.lua
2015-03-24 02:42:01,017 DEBUG    faf.updater          init_ladder1v1.lua is up to date.
2015-03-24 02:42:01,017 DEBUG    faf.updater          Updates applied successfully.
2015-03-24 02:42:01,017 DEBUG    faf.updater          writeToServer(GET_FILES_TO_UPDATE, [FAFGAMEDATA])
2015-03-24 02:42:01,059 DEBUG    faf.updater          handleAction(LIST_FILES_TO_UP) - 352 bytes
2015-03-24 02:42:01,059 DEBUG    faf.updater          Files to update: [faforever.faf, effects.nx2, env.nx2, loc.nx2, l
ua.nx2, meshes.nx2, modules.nx2, projectiles.nx2, schook.nx2, textures.nx2, units.nx2]
2015-03-24 02:42:01,062 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, schook.nx2, 160f4ea3749dd09be54d
cbc915355a8c])
2015-03-24 02:42:01,062 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, effects.nx2, 03bedb5b662b48602c3
e5f443d4bdd80])
2015-03-24 02:42:01,063 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, modules.nx2, 5580aa219315a88af3b
6b63791cda1e7])
2015-03-24 02:42:01,065 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, lua.nx2, 78bd03ad2e92d4a594e3b99
2a64eee8c])
2015-03-24 02:42:01,081 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, textures.nx2, fc10ede3cbb1479055
7803f4a69c8bec])
2015-03-24 02:42:01,082 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, env.nx2, c8e06a7315aa17c5ed7e2cc
6d6ecee99])
2015-03-24 02:42:01,082 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, meshes.nx2, e5dbdaf65e7820b60a39
8eb98136fa4c])
2015-03-24 02:42:01,101 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, faforever.faf, 51c196294638c2207
1dc8ee01e55771e])
2015-03-24 02:42:01,102 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, loc.nx2, b761276596401c04dac01c8
1463891c3])
2015-03-24 02:42:01,104 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, projectiles.nx2, a20c07ca43cab16
24cb9d75c804a3b23])
2015-03-24 02:42:01,211 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, units.nx2, 7c66db67cb2c72ffcb8e0
984915f8012])
2015-03-24 02:42:01,255 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 48 bytes
2015-03-24 02:42:01,255 DEBUG    faf.updater          file : schook.nx2
2015-03-24 02:42:01,255 DEBUG    faf.updater          schook.nx2 is up to date.
2015-03-24 02:42:01,305 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 50 bytes
2015-03-24 02:42:01,305 DEBUG    faf.updater          file : effects.nx2
2015-03-24 02:42:01,305 DEBUG    faf.updater          effects.nx2 is up to date.
2015-03-24 02:42:01,306 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 50 bytes
2015-03-24 02:42:01,306 DEBUG    faf.updater          file : modules.nx2
2015-03-24 02:42:01,306 DEBUG    faf.updater          modules.nx2 is up to date.
2015-03-24 02:42:01,306 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 42 bytes
2015-03-24 02:42:01,306 DEBUG    faf.updater          file : lua.nx2
2015-03-24 02:42:01,306 DEBUG    faf.updater          lua.nx2 is up to date.
2015-03-24 02:42:01,308 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 52 bytes
2015-03-24 02:42:01,308 DEBUG    faf.updater          file : textures.nx2
2015-03-24 02:42:01,308 DEBUG    faf.updater          textures.nx2 is up to date.
2015-03-24 02:42:01,308 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 42 bytes
2015-03-24 02:42:01,308 DEBUG    faf.updater          file : env.nx2
2015-03-24 02:42:01,308 DEBUG    faf.updater          env.nx2 is up to date.
2015-03-24 02:42:01,309 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 48 bytes
2015-03-24 02:42:01,309 DEBUG    faf.updater          file : meshes.nx2
2015-03-24 02:42:01,309 DEBUG    faf.updater          meshes.nx2 is up to date.
2015-03-24 02:42:01,309 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 54 bytes
2015-03-24 02:42:01,309 DEBUG    faf.updater          file : faforever.faf
2015-03-24 02:42:01,309 DEBUG    faf.updater          faforever.faf is up to date.
2015-03-24 02:42:01,309 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 42 bytes
2015-03-24 02:42:01,309 DEBUG    faf.updater          file : loc.nx2
2015-03-24 02:42:01,309 DEBUG    faf.updater          loc.nx2 is up to date.
2015-03-24 02:42:01,309 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 58 bytes
2015-03-24 02:42:01,309 DEBUG    faf.updater          file : projectiles.nx2
2015-03-24 02:42:01,309 DEBUG    faf.updater          projectiles.nx2 is up to date.
2015-03-24 02:42:01,311 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 46 bytes
2015-03-24 02:42:01,311 DEBUG    faf.updater          file : units.nx2
2015-03-24 02:42:01,311 DEBUG    faf.updater          units.nx2 is up to date.
2015-03-24 02:42:01,311 DEBUG    faf.updater          Updates applied successfully.
2015-03-24 02:42:01,311 DEBUG    faf.updater          writeToServer(GET_FILES_TO_UPDATE, [coop])
2015-03-24 02:42:01,354 DEBUG    faf.updater          handleAction(LIST_FILES_TO_UP) - 74 bytes
2015-03-24 02:42:01,354 DEBUG    faf.updater          Files to update: [init_coop.lua]
2015-03-24 02:42:01,357 DEBUG    faf.updater          writeToServer(UPDATE, [bin, init_coop.lua, 589bc2dd251f0523cda234
6d5521ab2c])
2015-03-24 02:42:01,401 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 54 bytes
2015-03-24 02:42:01,401 DEBUG    faf.updater          file : init_coop.lua
2015-03-24 02:42:01,401 DEBUG    faf.updater          init_coop.lua is up to date.
2015-03-24 02:42:01,401 DEBUG    faf.updater          Updates applied successfully.
2015-03-24 02:42:01,401 DEBUG    faf.updater          writeToServer(GET_FILES_TO_UPDATE, [coopGamedata])
2015-03-24 02:42:01,443 DEBUG    faf.updater          handleAction(LIST_FILES_TO_UP) - 156 bytes
2015-03-24 02:42:01,443 DEBUG    faf.updater          Files to update: [lobby_coop.cop, lua.nx2, textures.nx2, units.nx
2]
2015-03-24 02:42:01,447 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, lobby_coop.cop, 8af8305aaa1e9627
749f32b61585ad3d])
2015-03-24 02:42:01,450 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, lua.nx2, 78bd03ad2e92d4a594e3b99
2a64eee8c])
2015-03-24 02:42:01,464 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, textures.nx2, fc10ede3cbb1479055
7803f4a69c8bec])
2015-03-24 02:42:01,575 DEBUG    faf.updater          writeToServer(UPDATE, [gamedata, units.nx2, 7c66db67cb2c72ffcb8e0
984915f8012])
2015-03-24 02:42:01,617 DEBUG    faf.updater          handleAction(UP_TO_DATE) - 56 bytes
2015-03-24 02:42:01,618 DEBUG    faf.updater          file : lobby_coop.cop
2015-03-24 02:42:01,618 DEBUG    faf.updater          lobby_coop.cop is up to date.
2015-03-24 02:42:01,677 DEBUG    faf.updater          handleAction(SEND_PATCH_URL) - 226 bytes
2015-03-24 02:42:02,322 DEBUG    faf.updater          File downloaded successfully.
2015-03-24 02:42:02,869 DEBUG    faf.updater          gamedata/lua.nx2 is patched.
2015-03-24 02:42:02,871 DEBUG    faf.updater          handleAction(SEND_PATCH_URL) - 256 bytes
2015-03-24 02:42:03,799 DEBUG    faf.updater          File downloaded successfully.
2015-03-24 02:42:03,875 DEBUG    faf.updater          gamedata/textures.nx2 is patched.
2015-03-24 02:42:03,878 DEBUG    faf.updater          handleAction(SEND_PATCH_URL) - 238 bytes
2015-03-24 02:42:04,457 DEBUG    faf.updater          File downloaded successfully.
2015-03-24 02:42:04,709 DEBUG    faf.updater          gamedata/units.nx2 is patched.
2015-03-24 02:42:04,730 DEBUG    faf.updater          Updates applied successfully.
2015-03-24 02:42:04,730 DEBUG    faf.updater          Disconnected from server at 2015-03-24 02:42:04
2015-03-24 02:42:04,760 DEBUG    faf.updater          Update finished at 2015-03-24 02:42:04
2015-03-24 02:42:04,762 INFO     faf.fa               Writing fa_path.lua config file.
2015-03-24 02:42:04,767 INFO     faf.fa               Updating FA for map: x1ca_coop_001_v07
2015-03-24 02:42:08,786 INFO     faf.fa               Running FA with info: None
2015-03-24 02:42:08,788 INFO     faf.fa               Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAllia
nce.exe" /replay gpgnet://lobby.faforever.com/3241275/Myronovandis.SCFAreplay /init init_coop.lua /nobugreport /log "C:
\ProgramData\FAForever\logs\replay.log" /replayid 3241275
2015-03-24 02:42:09,344 INFO     faf.client           FA has launched in an attached process.
2015-03-24 02:42:09,344 INFO     faf.client           Outgoing JSON Message: {"state": "on", "command": "fa_state"}
2015-03-24 02:42:09,344 INFO     faf.fa               Viewing Replay.
2015-03-24 02:42:14,012 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 02:42:14,012 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 02:42:14,012 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 02:42:14,012 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 02:42:14,012 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
<goes on forever>

@Crotalus Crotalus added the bug label Mar 24, 2015
@vnznznz
Copy link
Contributor

vnznznz commented Mar 24, 2015

I recently experienced this or a similar bug too, is it possible that the client doesn't rotate logs sometimes? My forever.log was like 50GB big , unfortunately I deleted it without looking into its contents.

@vnznznz
Copy link
Contributor

vnznznz commented Mar 24, 2015

Reproduced: Start FA Forever Lobby -> Chat Lobby -> Right Click on User "vinzBad" -> View Player Statistics -> Global Rating Evolution for the last 90 days -> Pop-Up with message "Connecting to server" hangs -> after ca. 10s the log starts to grow rapidly

2015-03-24 21:44:24,713 DEBUG    faf.downloader       Searching map preview for: waters of isis 4v4
2015-03-24 21:45:49,260 INFO     faf.client           Outgoing JSON Message: {"player": "vinzBad", "type": "global_90_days", "command": "stats"}
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:11,305 ERROR    faf.secondaryServer  Operation timed out while waiting for info.

fa_bug

@vnznznz
Copy link
Contributor

vnznznz commented Mar 24, 2015

Seems like closing the window doesn't stop https://github.com/FAForever/client/blob/432476bd8a8c1842ca86223cd3c59f6ff09a1a16/src/secondaryServer/secondaryserver.py from spamming.

2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,115 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation timed out while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation aborted while waiting for info.
2015-03-24 21:46:47,117 ERROR    faf.secondaryServer  Operation timed out while waiting for info.

@Sheeo
Copy link
Member

Sheeo commented Mar 24, 2015

Right.

The real problem is here: https://github.com/FAForever/client/blob/develop/src/secondaryServer/secondaryserver.py#L144

Not sure if we can do something serverside to fix this

@vnznznz
Copy link
Contributor

vnznznz commented Mar 25, 2015

I think this method should throw an error if there are too many timeouts.

@Sheeo Sheeo mentioned this issue Mar 29, 2015
Closed
IDragonfire added a commit to IDragonfire/server that referenced this issue Mar 29, 2015
@IDragonfire
always send data back to client
fa95688 
related to FAForever/client#187
@vnznznz vnznznz mentioned this issue Apr 5, 2015
Closed
@anihilnine
Copy link

I get this error by searching the mod vault for "plus" and have to kill the .exe via task manager

@Sheeo
Copy link
Member

Sheeo commented May 15, 2016

This is definitely solved with the new logging mechanism.

@Sheeo Sheeo closed this as completed May 15, 2016
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Limit the waitForInfo loop. vnznznz/client
4 participants
@Sheeo @Crotalus @vnznznz @anihilnine