Networking problems in 1.4.42V3 (Server slow, Client does not die after shutdown...) #6259

allentiak · 2020-02-04T22:31:42Z

Briefing

Started server. It takes almost 5 minutes to start.
Once the server started, I started one client. It gave a DB lock error. I tried a few more times, until they started ("synchronized" works fine here, albeit slowly...).
Afterwards, I closed all the clients and manually purged the lock file xmage/xmage/mage-client/db/cards.h2.lock.db. It seems there is some undead client process, as it used "synchronized" again...
After closing all the clients and the server (in that order), the lock file remains (!)
I did a ps aux|grep java. Two clients are still running in the background! Killed them.
Started all over again. (This time, with only one client.) The client takes some time to start, but the process is killed normally when exiting...

Full logs below.

Logs

Server log

INFO  2020-02-04 18:43:48,707 Logging level: INFO                                                                        =>[main] Main.main 
INFO  2020-02-04 18:43:48,911 Loading database...                                                                        =>[main] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:44:55,098 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 18:44:55,099 Loading extension packages...                                                              =>[main] Main.main 
INFO  2020-02-04 18:44:55,099 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 18:44:55,099 Loading cards...                                                                           =>[main] Main.main 
INFO  2020-02-04 18:45:01,847 DB: need to add 35 new cards                                                               =>[main] CardRepository.lambda$saveCards$0 
INFO  2020-02-04 18:45:01,880 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 18:45:01,880 Updating user stats DB...                                                                  =>[main] Main.main 
INFO  2020-02-04 18:45:01,970 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 18:45:02,165 Config - max seconds idle: 600                                                             =>[main] Main.main 
INFO  2020-02-04 18:45:02,166 Config - max game threads: 10                                                              =>[main] Main.main 
INFO  2020-02-04 18:45:02,166 Config - max AI opponents: 15                                                              =>[main] Main.main 
INFO  2020-02-04 18:45:02,166 Config - min usr name le.: 3                                                               =>[main] Main.main 
INFO  2020-02-04 18:45:02,166 Config - max usr name le.: 14                                                              =>[main] Main.main 
INFO  2020-02-04 18:45:02,168 Config - min pswrd length: 8                                                               =>[main] Main.main 
INFO  2020-02-04 18:45:02,168 Config - max pswrd length: 100                                                             =>[main] Main.main 
INFO  2020-02-04 18:45:02,168 Config - inv.usr name pat: [^a-z0-9_]                                                      =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - save game active: false                                                           =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - backlog size    : 200                                                             =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - lease period    : 5000                                                            =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - sock wrt timeout: 10000                                                           =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - max pool size   : 300                                                             =>[main] Main.main 
INFO  2020-02-04 18:45:02,169 Config - num accp.threads: 2                                                               =>[main] Main.main 
INFO  2020-02-04 18:45:02,170 Config - second.bind port: -1                                                              =>[main] Main.main 
INFO  2020-02-04 18:45:02,170 Config - auth. activated : false                                                           =>[main] Main.main 
INFO  2020-02-04 18:45:02,170 Config - mailgun api key :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,170 Config - mailgun domain  :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,170 Config - mail smtp Host  :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,171 Config - mail smtpPort   :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,171 Config - mail user       :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,171 Config - mail passw. len.: 0                                                               =>[main] Main.main 
INFO  2020-02-04 18:45:02,171 Config - mail from addre.:                                                                 =>[main] Main.main 
INFO  2020-02-04 18:45:02,172 Config - google account  :                                                                 =>[main] Main.main 
INFO  2020-02-04 18:47:11,916 Started MAGE server - listening on 0.0.0.0:17171/?serializationtype=jboss&maxPoolSize=300  =>[main] Main.main 
INFO  2020-02-04 18:50:32,166 allentiak connected to server                                                              =>[WorkerThread#0[192.168.1.35:40270]] SessionManager.connectUser 
INFO  2020-02-04 18:50:32,166 allentiak connected to server                                                              =>[WorkerThread#1[192.168.1.35:40272]] SessionManager.connectUser 
INFO  2020-02-04 18:50:51,099 Disconnecting another user instance: allentiak                                             =>[WorkerThread#4[192.168.1.35:40320]] Session.connectUserHandling 
INFO  2020-02-04 18:50:51,104 allentiak connected to server                                                              =>[WorkerThread#4[192.168.1.35:40320]] SessionManager.connectUser 
INFO  2020-02-04 18:50:51,321 Disconnecting another user instance: allentiak                                             =>[WorkerThread#5[192.168.1.35:40324]] Session.connectUserHandling 
INFO  2020-02-04 18:50:51,322 allentiak connected to server                                                              =>[WorkerThread#5[192.168.1.35:40324]] SessionManager.connectUser 
INFO  2020-02-04 18:51:06,957 USER REMOVE - allentiak (Disconnected)  userId: e8ceff8b-7951-4927-8241-97f2b129e32a []    =>[CALL main-42] UserManager.lambda$null$1 
INFO  2020-02-04 18:51:26,002 USER REMOVE - allentiak (Disconnected)  userId: fef6b088-0b3f-4d3c-81cf-1e009e8af9f6 []    =>[CALL main-42] UserManager.lambda$null$1 
INFO  2020-02-04 18:58:29,137 Reconnecting session for allentiak                                                         =>[WorkerThread#0[192.168.1.35:40560]] Session.connectUserHandling 
INFO  2020-02-04 18:58:29,146 allentiak connected to server                                                              =>[WorkerThread#0[192.168.1.35:40560]] SessionManager.connectUser 
INFO  2020-02-04 18:58:55,608 USER REMOVE - allentiak (Disconnected)  userId: e8ceff8b-7951-4927-8241-97f2b129e32a []    =>[CALL main-54] UserManager.lambda$null$1 
INFO  2020-02-04 19:01:01,284 Reconnecting session for allentiak                                                         =>[WorkerThread#0[192.168.1.35:40646]] Session.connectUserHandling 
INFO  2020-02-04 19:01:01,292 allentiak connected to server                                                              =>[WorkerThread#0[192.168.1.35:40646]] SessionManager.connectUser 
INFO  2020-02-04 19:01:47,567 USER REMOVE - allentiak (Disconnected)  userId: e8ceff8b-7951-4927-8241-97f2b129e32a []    =>[CALL main-57] UserManager.lambda$null$1 
INFO  2020-02-04 19:10:38,297 Starting MAGE server version 1.4.42V3 (build: 2020-02-01 10:06)                            =>[main] Main.main 
INFO  2020-02-04 19:10:38,299 Logging level: INFO                                                                        =>[main] Main.main 
INFO  2020-02-04 19:10:38,457 Loading database...                                                                        =>[main] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 19:11:43,671 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 19:11:43,671 Loading extension packages...                                                              =>[main] Main.main 
INFO  2020-02-04 19:11:43,671 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 19:11:43,672 Loading cards...                                                                           =>[main] Main.main 
INFO  2020-02-04 19:11:50,142 DB: need to add 35 new cards                                                               =>[main] CardRepository.lambda$saveCards$0 
INFO  2020-02-04 19:11:50,168 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 19:11:50,168 Updating user stats DB...                                                                  =>[main] Main.main 
INFO  2020-02-04 19:11:50,248 Done.                                                                                      =>[main] Main.main 
INFO  2020-02-04 19:11:50,397 Config - max seconds idle: 600                                                             =>[main] Main.main 
INFO  2020-02-04 19:11:50,397 Config - max game threads: 10                                                              =>[main] Main.main 
INFO  2020-02-04 19:11:50,397 Config - max AI opponents: 15                                                              =>[main] Main.main 
INFO  2020-02-04 19:11:50,397 Config - min usr name le.: 3                                                               =>[main] Main.main 
INFO  2020-02-04 19:11:50,397 Config - max usr name le.: 14                                                              =>[main] Main.main 
INFO  2020-02-04 19:11:50,399 Config - min pswrd length: 8                                                               =>[main] Main.main 
INFO  2020-02-04 19:11:50,399 Config - max pswrd length: 100                                                             =>[main] Main.main 
INFO  2020-02-04 19:11:50,399 Config - inv.usr name pat: [^a-z0-9_]                                                      =>[main] Main.main 
INFO  2020-02-04 19:11:50,399 Config - save game active: false                                                           =>[main] Main.main 
INFO  2020-02-04 19:11:50,400 Config - backlog size    : 200                                                             =>[main] Main.main 
INFO  2020-02-04 19:11:50,400 Config - lease period    : 5000                                                            =>[main] Main.main 
INFO  2020-02-04 19:11:50,400 Config - sock wrt timeout: 10000                                                           =>[main] Main.main 
INFO  2020-02-04 19:11:50,400 Config - max pool size   : 300                                                             =>[main] Main.main 
INFO  2020-02-04 19:11:50,400 Config - num accp.threads: 2                                                               =>[main] Main.main 
INFO  2020-02-04 19:11:50,401 Config - second.bind port: -1                                                              =>[main] Main.main 
INFO  2020-02-04 19:11:50,401 Config - auth. activated : false                                                           =>[main] Main.main 
INFO  2020-02-04 19:11:50,401 Config - mailgun api key :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,401 Config - mailgun domain  :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,401 Config - mail smtp Host  :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,402 Config - mail smtpPort   :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,402 Config - mail user       :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,402 Config - mail passw. len.: 0                                                               =>[main] Main.main 
INFO  2020-02-04 19:11:50,402 Config - mail from addre.:                                                                 =>[main] Main.main 
INFO  2020-02-04 19:11:50,402 Config - google account  :                                                                 =>[main] Main.main 
INFO  2020-02-04 19:13:59,049 Started MAGE server - listening on 0.0.0.0:17171/?serializationtype=jboss&maxPoolSize=300  =>[main] Main.main 
INFO  2020-02-04 19:17:02,516 allentiak connected to server                                                              =>[WorkerThread#0[192.168.1.35:40956]] SessionManager.connectUser 
INFO  2020-02-04 19:21:41,770 USER REMOVE - allentiak (Disconnected)  userId: 3fb4d078-0d97-43c5-b3d5-299af275cff1 []    =>[CALL main-37] UserManager.lambda$null$1

Client log

INFO  2020-02-04 18:47:20,487 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:47:20,490 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:47:20,490 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:47:20,493 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:47:39,274 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:47:39,286 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
ERROR 2020-02-04 18:47:50,010 Error creating card repository -                                                           =>[AWT-EventQueue-0] CardRepository.<init> 
org.h2.jdbc.JdbcSQLException: Database may be already in use: "Locked by another process: /home/allentiak/opt/xmage/xmage/mage-client/db/cards.h2.lock.db". Possible solutions: close all other connection(s); use the server mode [90020-197]
	at org.h2.message.DbException.getJdbcSQLException(DbException.java:357)
	at org.h2.message.DbException.get(DbException.java:179)
	at org.h2.message.DbException.get(DbException.java:155)
	at org.h2.store.FileLock.getExceptionAlreadyInUse(FileLock.java:433)
	at org.h2.store.FileLock.lockFile(FileLock.java:325)
	at org.h2.store.FileLock.lock(FileLock.java:108)
	at org.h2.engine.Database.open(Database.java:674)
	at org.h2.engine.Database.openDatabase(Database.java:286)
	at org.h2.engine.Database.<init>(Database.java:280)
	at org.h2.engine.Engine.openSession(Engine.java:66)
	at org.h2.engine.Engine.openSession(Engine.java:179)
	at org.h2.engine.Engine.createSessionAndValidate(Engine.java:157)
	at org.h2.engine.Engine.createSession(Engine.java:140)
	at org.h2.engine.Engine.createSession(Engine.java:28)
	at org.h2.engine.SessionRemote.connectEmbeddedOrServer(SessionRemote.java:351)
	at org.h2.jdbc.JdbcConnection.<init>(JdbcConnection.java:124)
	at org.h2.jdbc.JdbcConnection.<init>(JdbcConnection.java:103)
	at org.h2.Driver.connect(Driver.java:69)
	at java.sql.DriverManager.getConnection(DriverManager.java:664)
	at java.sql.DriverManager.getConnection(DriverManager.java:208)
	at com.j256.ormlite.jdbc.JdbcConnectionSource.makeConnection(JdbcConnectionSource.java:266)
	at com.j256.ormlite.jdbc.JdbcConnectionSource.getReadWriteConnection(JdbcConnectionSource.java:187)
	at com.j256.ormlite.table.TableUtils.doCreateTable(TableUtils.java:397)
	at com.j256.ormlite.table.TableUtils.doCreateTable(TableUtils.java:383)
	at com.j256.ormlite.table.TableUtils.createTableIfNotExists(TableUtils.java:75)
	at mage.cards.repository.RepositoryUtil.isDatabaseObsolete(RepositoryUtil.java:32)
	at mage.cards.repository.CardRepository.<init>(CardRepository.java:51)
	at mage.cards.repository.CardRepository.<clinit>(CardRepository.java:29)
	at mage.cards.repository.RepositoryUtil.bootstrapLocalDb(RepositoryUtil.java:28)
	at mage.client.MageFrame.<init>(MageFrame.java:226)
	at mage.client.MageFrame.lambda$main$9(MageFrame.java:1305)
	at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
	at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:758)
	at java.awt.EventQueue.access$500(EventQueue.java:97)
	at java.awt.EventQueue$3.run(EventQueue.java:709)
	at java.awt.EventQueue$3.run(EventQueue.java:703)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:74)
	at java.awt.EventQueue.dispatchEvent(EventQueue.java:728)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:205)
	at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
	at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
FATAL 2020-02-04 18:47:50,015 MAGE Client UI error                                                                       =>[AWT-EventQueue-0] EDTExceptionHandler.handle 
java.lang.NullPointerException
	at mage.cards.repository.ExpansionRepository.getSetByCode(ExpansionRepository.java:171)
	at mage.cards.repository.RepositoryUtil.isDatabaseEmpty(RepositoryUtil.java:108)
	at mage.client.MageFrame.<init>(MageFrame.java:228)
	at mage.client.MageFrame.lambda$main$9(MageFrame.java:1305)
	at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
	at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:758)
	at java.awt.EventQueue.access$500(EventQueue.java:97)
	at java.awt.EventQueue$3.run(EventQueue.java:709)
	at java.awt.EventQueue$3.run(EventQueue.java:703)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:74)
	at java.awt.EventQueue.dispatchEvent(EventQueue.java:728)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:205)
	at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
	at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
INFO  2020-02-04 18:48:49,493 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:48:49,496 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:48:49,497 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:48:49,502 Logging level: INFO                                                                        =>[main] MageFrame.main 
FATAL 2020-02-04 18:48:52,447 MAGE Client UI error                                                                       =>[AWT-EventQueue-0] EDTExceptionHandler.handle 
java.lang.NullPointerException
	at mage.cards.repository.ExpansionRepository.getSetByCode(ExpansionRepository.java:171)
	at mage.cards.repository.RepositoryUtil.isDatabaseEmpty(RepositoryUtil.java:108)
	at mage.client.MageFrame.<init>(MageFrame.java:228)
	at mage.client.MageFrame.lambda$main$9(MageFrame.java:1305)
	at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
	at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:758)
	at java.awt.EventQueue.access$500(EventQueue.java:97)
	at java.awt.EventQueue$3.run(EventQueue.java:709)
	at java.awt.EventQueue$3.run(EventQueue.java:703)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:74)
	at java.awt.EventQueue.dispatchEvent(EventQueue.java:728)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:205)
	at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
	at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
INFO  2020-02-04 18:49:07,936 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:49:07,952 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:49:08,381 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:49:08,396 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:49:09,425 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:49:09,428 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:49:09,645 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:49:09,649 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:49:10,356 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:10,359 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:10,404 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,414 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,418 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,423 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,424 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,424 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:10,432 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:49:10,433 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-02-04 18:49:11,348 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
WARN  2020-02-04 18:49:11,352 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 18:49:11,418 Client start up time: 21 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:49:11,417 Client start up time: 21 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:49:11,420 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:49:11,419 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:49:11,453 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:49:11,494 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:49:27,474 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:49:27,672 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:49:27,734 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:49:27,961 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:49:29,348 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:29,402 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,411 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,415 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,415 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:29,533 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:49:29,590 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,598 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,603 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:49:29,604 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-02-04 18:49:30,293 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 18:49:30,349 Client start up time: 20 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:49:30,349 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:49:30,430 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
WARN  2020-02-04 18:49:30,540 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 18:49:30,614 Client start up time: 20 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:49:30,615 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:49:30,662 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:50:32,148 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:50:32,148 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:50:32,149 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:32,149 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:32,205 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:32,242 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:51,087 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:50:51,089 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:51,162 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:51,312 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:50:51,313 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:50:51,370 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:51:06,947 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:51:06,968 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:51:06,968 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected 
ERROR 2020-02-04 18:51:09,927 Ping failed: allentiak Session: 5c4o1z-w3hzdr-k68f2jyr-1-k68f2k03-4 to MAGE server at localhost:17171 =>[pool-1-thread-1] SessionImpl.ping 
FATAL 2020-02-04 18:51:09,929 Server error                                                                               =>[pool-1-thread-1] SessionImpl.handleMageException 
mage.MageException: Ping failed
	at mage.remote.SessionImpl.ping(SessionImpl.java:1667)
	at mage.client.SessionHandler.ping(SessionHandler.java:42)
	at mage.client.MageFrame.lambda$new$1(MageFrame.java:266)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
INFO  2020-02-04 18:51:09,952 Disconnecting...                                                                           =>[pool-1-thread-1] SessionImpl.disconnect 
INFO  2020-02-04 18:51:09,963 Disconnecting DONE                                                                         =>[pool-1-thread-1] SessionImpl.disconnect 
INFO  2020-02-04 18:51:10,006 Disconnected from user mode                                                                =>[pool-1-thread-1] MageFrame.disconnected 
ERROR 2020-02-04 18:51:10,896 Ping failed: allentiak Session: 5c4o1z-u6zv09-k68f25b4-1-k68f25c5-4 to MAGE server at localhost:17171 =>[pool-1-thread-1] SessionImpl.ping 
FATAL 2020-02-04 18:51:10,898 Server error                                                                               =>[pool-1-thread-1] SessionImpl.handleMageException 
mage.MageException: Ping failed
	at mage.remote.SessionImpl.ping(SessionImpl.java:1667)
	at mage.client.SessionHandler.ping(SessionHandler.java:42)
	at mage.client.MageFrame.lambda$new$1(MageFrame.java:266)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
INFO  2020-02-04 18:51:10,920 Disconnecting...                                                                           =>[pool-1-thread-1] SessionImpl.disconnect 
INFO  2020-02-04 18:51:10,936 Disconnecting DONE                                                                         =>[pool-1-thread-1] SessionImpl.disconnect 
INFO  2020-02-04 18:51:10,980 Disconnected from user mode                                                                =>[pool-1-thread-1] MageFrame.disconnected 
INFO  2020-02-04 18:51:25,998 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:51:26,011 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:51:26,011 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected 
INFO  2020-02-04 18:56:49,113 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:56:49,115 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:57:06,791 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:57:06,996 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:57:07,978 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:57:08,014 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:57:08,022 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:57:08,025 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:57:08,025 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-02-04 18:57:08,576 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 18:57:08,615 Client start up time: 19 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:57:08,616 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:57:08,643 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:58:29,132 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 18:58:29,133 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:58:29,177 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 18:58:55,604 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:58:55,618 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 18:58:55,618 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected 
INFO  2020-02-04 18:59:21,542 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 18:59:21,544 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 18:59:38,934 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 18:59:39,158 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 18:59:40,135 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 18:59:40,173 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 18:59:40,181 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 18:59:40,184 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 18:59:40,184 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-02-04 18:59:40,726 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 18:59:40,774 Client start up time: 19 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 18:59:40,775 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 18:59:40,806 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 19:01:01,280 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 19:01:01,281 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 19:01:01,320 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 19:01:47,564 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 19:01:47,575 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 19:01:47,576 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected 
INFO  2020-02-04 19:14:07,443 Starting MAGE client version 1.4.42V3 (build: 2020-02-01 10:03)                            =>[main] MageFrame.main 
INFO  2020-02-04 19:14:07,445 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-02-04 19:14:25,004 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-02-04 19:15:40,296 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-02-04 19:15:41,297 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-02-04 19:15:41,331 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-02-04 19:15:41,338 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-02-04 19:15:41,340 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-02-04 19:15:41,340 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-02-04 19:15:41,908 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-02-04 19:15:41,939 Client start up time: 94 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-02-04 19:15:41,939 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-02-04 19:15:41,955 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 19:17:02,496 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-02-04 19:17:02,497 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 19:17:02,563 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-02-04 19:21:41,757 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 19:21:41,781 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-02-04 19:21:41,782 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected

The text was updated successfully, but these errors were encountered:

allentiak · 2020-02-04T22:33:43Z

#6169 (comment)_

JayDi85 · 2020-02-04T22:43:40Z

About clients freezes:

Reason: you are runs multiple clients after update. On new version it must re-create database files. So run only one app for first time. If you got that problem (freezed clients) then close all java.exe apps, and clean up /xmage/mage-client/db/ folder.
It's not a bug.

Also check your client run script -- it's run multiple clients at the same time:

About server start up: it's wrong settings or freezes server app, see my comments from Feedback XMage Version 1.4.42 (January 2020) #6169 (comment)

allentiak · 2020-02-04T23:53:53Z

Thanks again for your comments, @JayDi85 !

Regarding the server startup time, I've noticed the launcher UI takes a long time to appear...

I plan to reinstall XMage. Could you please tell me how to backup/export my settings? I haven't been able to find the appropriate file(s)...
Could you please recommend me some profiling tool, so I know what could cause this? The launcher log provides me with no information regarding this... As there are no errors, it is blank.

JayDi85 · 2020-02-05T00:30:46Z

I'm using VisualVM -- its free and can connect to any java app. You can collect info about threads and time in methods/classes. Use that commit to find out about client settings: #4461 (comment) Server settings in config/config.xml file (you must save it before update). Also you need db folder for user registration and stats save.

allentiak · 2020-02-05T00:32:00Z

Thanks! I'll try this and let you know.

allentiak · 2020-03-22T22:48:07Z

Seems to be fixed with 1.4.42V6.

Even after card database rebuild, the server started in only 20s(!). See logs below.

Server log:

Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=384m; support was removed in 8.0
INFO  2020-03-22 19:37:18,999 Starting MAGE server version 1.4.42V6 (build: 2020-03-04 17:19)                            =>[main] Main.main 
INFO  2020-03-22 19:37:19,002 Logging level: INFO                                                                        =>[main] Main.main 
INFO  2020-03-22 19:37:19,282 Loading database...                                                                        =>[main] RepositoryUtil.bootstrapLocalDb 
INFO  2020-03-22 19:37:20,246 Done.                                                                                      =>[main] Main.main 
INFO  2020-03-22 19:37:20,246 Loading extension packages...                                                              =>[main] Main.main 
INFO  2020-03-22 19:37:20,246 Done.                                                                                      =>[main] Main.main 
INFO  2020-03-22 19:37:20,246 Loading cards...                                                                           =>[main] Main.main 
INFO  2020-03-22 19:37:25,215 DB: need to add 233 new sets                                                               =>[main] ExpansionRepository.lambda$saveSets$0 
INFO  2020-03-22 19:37:33,944 DB: need to add 38925 new cards                                                            =>[main] CardRepository.lambda$saveCards$0 
INFO  2020-03-22 19:37:39,424 Done.                                                                                      =>[main] Main.main 
INFO  2020-03-22 19:37:39,424 Updating user stats DB...                                                                  =>[main] Main.main 
INFO  2020-03-22 19:37:39,686 Done.                                                                                      =>[main] Main.main 
INFO  2020-03-22 19:37:39,855 Config - max seconds idle: 600                                                             =>[main] Main.main 
INFO  2020-03-22 19:37:39,855 Config - max game threads: 10                                                              =>[main] Main.main 
INFO  2020-03-22 19:37:39,855 Config - max AI opponents: 15                                                              =>[main] Main.main 
INFO  2020-03-22 19:37:39,855 Config - min usr name le.: 3                                                               =>[main] Main.main 
INFO  2020-03-22 19:37:39,856 Config - max usr name le.: 14                                                              =>[main] Main.main 
INFO  2020-03-22 19:37:39,856 Config - min pswrd length: 8                                                               =>[main] Main.main 
INFO  2020-03-22 19:37:39,856 Config - max pswrd length: 100                                                             =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - inv.usr name pat: [^a-z0-9_]                                                      =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - save game active: false                                                           =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - backlog size    : 200                                                             =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - lease period    : 5000                                                            =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - sock wrt timeout: 10000                                                           =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - max pool size   : 300                                                             =>[main] Main.main 
INFO  2020-03-22 19:37:39,857 Config - num accp.threads: 2                                                               =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - second.bind port: -1                                                              =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - auth. activated : false                                                           =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - mailgun api key :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - mailgun domain  :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - mail smtp Host  :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,858 Config - mail smtpPort   :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,859 Config - mail user       :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,859 Config - mail passw. len.: 0                                                               =>[main] Main.main 
INFO  2020-03-22 19:37:39,859 Config - mail from addre.:                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:39,859 Config - google account  :                                                                 =>[main] Main.main 
INFO  2020-03-22 19:37:40,768 Started MAGE server - listening on 0.0.0.0:17171/?serializationtype=jboss&maxPoolSize=300  =>[main] Main.main 
INFO  2020-03-22 19:38:27,522 allentiak connected to server                                                              =>[WorkerThread#0[192.168.1.34:44784]] SessionManager.connectUser 
INFO  2020-03-22 19:45:41,493 USER REMOVE - allentiak (Disconnected)  userId: ebe8fee3-15be-44dc-a8ff-58afa25848d0 []    =>[CALL main-36] UserManager.lambda$null$1

Client log:

Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=384m; support was removed in 8.0
INFO  2020-03-22 19:37:51,894 Starting MAGE client version 1.4.42V6 (build: 2020-03-04 17:17)                            =>[main] MageFrame.main 
INFO  2020-03-22 19:37:51,897 Logging level: INFO                                                                        =>[main] MageFrame.main 
INFO  2020-03-22 19:37:58,334 Loading database...                                                                        =>[AWT-EventQueue-0] RepositoryUtil.bootstrapLocalDb 
INFO  2020-03-22 19:37:59,356 DB: creating cards database                                                                =>[AWT-EventQueue-0] MageFrame.<init> 
INFO  2020-03-22 19:38:04,867 DB: need to add 233 new sets                                                               =>[AWT-EventQueue-0] ExpansionRepository.lambda$saveSets$0 
INFO  2020-03-22 19:38:14,949 DB: need to add 38925 new cards                                                            =>[AWT-EventQueue-0] CardRepository.lambda$saveCards$0 
INFO  2020-03-22 19:38:21,706 Done.                                                                                      =>[AWT-EventQueue-0] MageFrame.<init> 
INFO  2020-03-22 19:38:21,726 Loading symbols...                                                                         =>[AWT-EventQueue-0] ManaSymbols.loadImages 
INFO  2020-03-22 19:38:25,137 Loading plugins...                                                                         =>[AWT-EventQueue-0] Plugins.loadPlugins 
INFO  2020-03-22 19:38:25,216 [Game counter plugin, version 0.1] has been loaded.                                        =>[AWT-EventQueue-0] CounterPluginImpl.newPlugin 
INFO  2020-03-22 19:38:25,238 [Card plugin, version 0.7] has been loaded.                                                =>[AWT-EventQueue-0] CardPluginImpl.newPlugin 
INFO  2020-03-22 19:38:25,242 [Theme plugin, version 0.5] has been loaded.                                               =>[AWT-EventQueue-0] ThemePluginImpl.newPlugin 
INFO  2020-03-22 19:38:25,243 Done.                                                                                      =>[AWT-EventQueue-0] Plugins.loadPlugins 
WARN  2020-03-22 19:38:26,535 SystemTray is not supported                                                                =>[AWT-EventQueue-0] MageTray.install 
INFO  2020-03-22 19:38:26,590 Client start up time: 34 seconds                                                           =>[AWT-EventQueue-0] MageFrame.lambda$new$2 
INFO  2020-03-22 19:38:26,591 Auto-connecting to localhost                                                               =>[AWT-EventQueue-0] MageFrame.autoConnect 
INFO  2020-03-22 19:38:26,655 Connect: connecting to server localhost:17171                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-03-22 19:38:27,504 Connect: DONE                                                                              =>[SwingWorker-pool-5-thread-2] SessionImpl$5.work 
INFO  2020-03-22 19:38:27,504 Logging: as username allentiak to server localhost:17171                                   =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-03-22 19:38:27,568 Logging: DONE                                                                              =>[SwingWorker-pool-5-thread-3] SessionImpl$4.work 
INFO  2020-03-22 19:45:41,483 Disconnecting...                                                                           =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-03-22 19:45:41,509 Disconnecting DONE                                                                         =>[AWT-EventQueue-0] SessionImpl.disconnect 
INFO  2020-03-22 19:45:41,510 Disconnected from remote task                                                              =>[AWT-EventQueue-0] MageFrame.disconnected

allentiak mentioned this issue Feb 4, 2020

Feedback XMage Version 1.4.42 (January 2020) #6169

Open

allentiak closed this as completed Mar 22, 2020

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Networking problems in 1.4.42V3 (Server slow, Client does not die after shutdown...) #6259

Networking problems in 1.4.42V3 (Server slow, Client does not die after shutdown...) #6259

allentiak commented Feb 4, 2020

allentiak commented Feb 4, 2020

JayDi85 commented Feb 4, 2020

allentiak commented Feb 4, 2020

JayDi85 commented Feb 5, 2020 via email

allentiak commented Feb 5, 2020

allentiak commented Mar 22, 2020 •

edited

Networking problems in 1.4.42V3 (Server slow, Client does not die after shutdown...) #6259

Networking problems in 1.4.42V3 (Server slow, Client does not die after shutdown...) #6259

Comments

allentiak commented Feb 4, 2020

Briefing

Logs

Server log

Client log

allentiak commented Feb 4, 2020

JayDi85 commented Feb 4, 2020

allentiak commented Feb 4, 2020

JayDi85 commented Feb 5, 2020 via email

allentiak commented Feb 5, 2020

allentiak commented Mar 22, 2020 • edited

allentiak commented Mar 22, 2020 •

edited