Konkiti
konki****@users*****
2003年 8月 17日 (日) 21:29:25 JST
Konkiti です。 > 2003-07-06 14:02:19,758 WARN [rumblejp.ejb.session.BattleManagerBean] > Wrong confirm_string or timeout. > [league:4, season:50, division:49, machineId:8] > > というメッセージが100%出ていました。 このメッセージが出るカラクリは、バトル終了時に 10分以上経過している ため、まず distribute/Server.java の前回通信時刻より10分以上経過の条件 に引っかかり途中経過(melee の場合 1 division 分の結果)を送信し反映させ ます。 次に 1 division 分の結果を送信し反映させようとしますが、そのときには divisions テーブル上の confirm_string はクリアされているので、ここで 件のメッセージが出力されるのではないかと思います。 何パターンかだけ見てみましたが、実際にバトルの割り当てから終了まで 10分 以上かかっていました。 > このメッセージが出たからといってdivisionの結果がゼロになるわけではない > #大体結果ゼロdivisionの数の3倍くらい、このメッセージが出ている。 > んですが、強い関連性があると考えられます。 メッセージの出る理由はわかりましたが、結果ゼロになってしまう理由が 未だにわかりません。(もう何日つぶしたことか… ただ、いただいた JBoss のログを見ていて気づいたことがあります。 結果ゼロ division の反映が終った後に分散サーバに次のバトル(division)の 割り当てが行われますが、その割り当てられたはずの division がその数分後 に再び割り当てられているのです。 2 回目の割り当ては同じ分散サーバに対しての場合もありますし、別の分散 サーバに対しての場合もあります。 divisions テーブルの更新時刻を見ると開始日時は最初の割り当て、終了日時 は 2回目の割り当ての対戦結果が返って来たときのようです。 これってどういう事でしょう? ご参考までに 3パターンほど例を添付します。下記の結果ゼロ division に ついてのログとテーブル内容です。 [league:4, season:16, division:6] [league:4, season:16, division:17] [league:4, season:39, division:5] Konkiti. -------------- next part -------------- 2003-06-24 15:28:03,283 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:6] to machine[id:6]. 2003-06-24 15:43:13,174 DEBUG [rumblejp.webservices.Battles] Robot's data file synchronization start. [kuro2.Bambino2] 2003-06-24 15:43:14,783 WARN [rumblejp.ejb.session.BattleManagerBean] Wrong confirm_string or timeout. [league:4, season:16, division:6, machineId:6] 2003-06-24 15:43:14,799 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:10] to machine[id:6]. 2003-06-24 15:45:12,815 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:10] to machine[id:6]. 2003-06-24 15:52:18,799 DEBUG [rumblejp.webservices.Battles] Robot's data file synchronization start. [sm.Devil] 2003-06-24 15:52:18,955 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:3, season:9, division:8] to machine[id:6]. again battles +-----------+-----------+--------+----------+--------+------------+---------------------+ | battle_id | league_id | season | division | status | machine_id | finish_date | +-----------+-----------+--------+----------+--------+------------+---------------------+ | 118951 | 4 | 16 | 6 | 2 | 6 | 2003-06-24 15:43:13 | | 118955 | 4 | 16 | 10 | 2 | 6 | 2003-06-24 15:52:18 | +-----------+-----------+--------+----------+--------+------------+---------------------+ divisions +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | league_id | season | division | status | machine_id | start_date | finish_date | last_access | expiration | confirm_string | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | 4 | 16 | 6 | 2 | 6 | 2003-06-24 15:28:03 | 2003-06-24 15:43:13 | 2003-06-24 15:43:13 | NULL | NULL | | 4 | 16 | 10 | 2 | 6 | 2003-06-24 15:43:14 | 2003-06-24 15:52:18 | 2003-06-24 15:52:18 | NULL | NULL | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ ====================================================================== 2003-06-24 16:09:00,033 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:17] to machine[id:4]. 2003-06-24 16:24:02,408 DEBUG [rumblejp.webservices.Battles] Robot's data file synchronization start. [tkrobot.TkRobot01] 2003-06-24 16:24:02,721 WARN [rumblejp.ejb.session.BattleManagerBean] Wrong confirm_string or timeout. [league:4, season:16, division:17, machineId:4] 2003-06-24 16:24:02,752 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:22] to machine[id:4]. 2003-06-24 16:25:43,362 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:22] to machine[id:8]. 2003-06-24 16:28:03,940 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:16, division:23] to machine[id:8]. again battles +-----------+-----------+--------+----------+--------+------------+---------------------+ | battle_id | league_id | season | division | status | machine_id | finish_date | +-----------+-----------+--------+----------+--------+------------+---------------------+ | 118962 | 4 | 16 | 17 | 2 | 4 | 2003-06-24 16:24:02 | | 118967 | 4 | 16 | 22 | 2 | 8 | 2003-06-24 16:28:03 | +-----------+-----------+--------+----------+--------+------------+---------------------+ divisions +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | league_id | season | division | status | machine_id | start_date | finish_date | last_access | expiration | confirm_string | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | 4 | 16 | 17 | 2 | 4 | 2003-06-24 16:09:00 | 2003-06-24 16:24:02 | 2003-06-24 16:24:02 | NULL | NULL | | 4 | 16 | 22 | 2 | 8 | 2003-06-24 16:24:02 | 2003-06-24 16:28:03 | 2003-06-24 16:28:03 | NULL | NULL | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ ====================================================================== 2003-07-03 20:00:04,594 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:39, division:5] to machine[id:4]. 2003-07-03 20:15:09,047 DEBUG [rumblejp.webservices.Battles] Robot's data file synchronization start. [akkyi.RedRuler] 2003-07-03 20:15:10,031 WARN [rumblejp.ejb.session.BattleManagerBean] Wrong confirm_string or timeout. [league:4, season:39, division:5, machineId:4] 2003-07-03 20:15:10,063 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:3, season:21, division:29] to machine[id:4]. 2003-07-03 20:19:30,516 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:3, season:21, division:29] to machine[id:8]. 2003-07-03 20:22:27,875 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:4, season:39, division:8] to machine[id:8]. 2003-07-03 20:25:03,610 DEBUG [rumblejp.webservices.Battles] Robot's data file synchronization start. [kuro2.Bambino2] 2003-07-03 20:25:03,922 DEBUG [rumblejp.ejb.session.BattleManagerBean] Assigned division[league:3, season:21, division:30] to machine[id:4]. again battles +-----------+-----------+--------+----------+--------+------------+---------------------+ | battle_id | league_id | season | division | status | machine_id | finish_date | +-----------+-----------+--------+----------+--------+------------+---------------------+ | 132879 | 4 | 39 | 5 | 2 | 4 | 2003-07-03 20:15:09 | | 132172 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132173 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132174 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132175 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132176 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132177 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132178 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132179 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132180 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | | 132181 | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:22:27 | +-----------+-----------+--------+----------+--------+------------+---------------------+ divisions +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | league_id | season | division | status | machine_id | start_date | finish_date | last_access | expiration | confirm_string | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+ | 4 | 39 | 5 | 2 | 4 | 2003-07-03 20:00:04 | 2003-07-03 20:15:09 | 2003-07-03 20:15:09 | NULL | NULL | | 3 | 21 | 29 | 2 | 8 | 2003-07-03 20:15:10 | 2003-07-03 20:22:27 | 2003-07-03 20:22:27 | NULL | NULL | +-----------+--------+----------+--------+------------+---------------------+---------------------+---------------------+------------+----------------+
