「定期受信できず」が頻繁に発生します - 秀丸メール情報交換会議室 - スレッド36882, 2009/08/20

「定期受信できず」が頻繁に発生します

もものしん さん　09/08/20 12:01

お世話になります。最近、「定期受信できず」が頻発するため質問
します。

環境:OS Windows VISTA Business SP1
PC 富士通 FMV-D5360
ネットワーク会社に敷設されている1000BASE-T
常時接続タイプで安定しています。
秀丸メール Ver 5.20

現象:秀丸メールをアイコン化した状態で定期受信を行ってます。
数十分経過するとアイコンの表示が
「秀丸メール定期受信できず」と変化し、それ以降は
定期受信を行いません。
アイコン状態から元のサイズに戻すと定期受信を再開
します。
この現象が発生し始めたのは、メールサーバが変更に
なり、受信メールサーバのアドレスを変更してからです。

設定: 過去ログにあった「定期受信できず」に影響する設定の
「定期受信の条件」の全てのチェックボックスはオフ
(なにもチェックを入れてない)の状態です。

ログ: 過去ログに「動作の記録」でdump.txtを記録し、
FrameWndProc() IDTIMER_AUTODOWN 以降の情報が必要と
ありましたので、該当個所を以下に記載します。

11:20:35.759 (7289) FrameWndProc() IDTIMER_AUTODOWN
11:20:35.759 (1490) IsProcessExitable: ShowFrame
11:20:35.760 (1496) IsProcessExitable: editor > 1
11:20:35.761 (14707) StopAutoDownTimer()
11:20:35.762 (8072) CTrans::AutoDown()
11:20:35.763 (8076) AutoDown return TRUE
11:20:35.764 (14687) StartAutoDownTimer()
11:20:35.765 (14695) StartAutoDownTimer() set
11:20:49.252 (8251) -
11:20:49.263 (7849) FreePatrol
11:20:49.264 (8539) LeaveTrans()
11:20:49.264 (14350) 40017 ret 1
11:20:52.930 (7115) syscommand 0xF012
11:20:52.931 (7176) will DefWindowProc
11:20:54.387 (7180) DefWindowProc done nRet = 00000000
11:20:56.034 (7115) syscommand 0xF095
11:20:56.035 (7176) will DefWindowProc
11:21:06.883 (7180) DefWindowProc done nRet = 00000000
11:21:06.884 (12923) Cmd 40017
11:21:06.890 (8436) EnterTrans
11:21:06.891 (8464) Trans mutex
11:21:06.892 (7818) InitPatrol

上記の情報から「定期受信できず」の原因は分かりますか?
また、「定期受信できず」が発生しても、自動的に定期受信を
復旧させる方法ありますか?

以上、よろしくお願いします。

RE:36882 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/08/20 13:30

　そのdump.txtだとちょっと原因が分からないです。

> 11:20:35.762 (8072) CTrans::AutoDown()
> 11:20:35.763 (8076) AutoDown return TRUE

　これは、定期受信を試みたけども、ある特定の条件が関係して定期受信しない
でreturnしています。しかもアイコン／タイトルバーを変化させる処理もしてい
ません。

　なぜそうなるかというと、実はその後ろにある

> 11:20:49.264 (14350) 40017 ret 1

　で分かります。40017のコマンド実行を終了した記録があります。これは、実
は「全般的な設定」のコマンドでして、つまり、全般的な設定をやっている最中
に定期受信のタイマーが発生したけども、全般的な設定の最中なので何もしなか
った、ということになります。

　ということでこの記録では分からないです。

　ログとしては、まずは、

CTrans::AutoDown()

　があって、その後必ず

SetReceivedTaskbarIcon Set!

　のような記録が出ているはずだと思います。そういう記録のある所を書き込ん
で欲しいです。

RE:36883 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/08/20 13:56

　dump.txtの中から以下の文字列を探していただければ分かるかなぁと思います。

(8179) AutoDown return TRUE
(8186) AutoDown LastActivePopup exist
(8189) CTrans::AutoDown() LastActivePopup is notifywnd
(8194) AutoDown Captured
(8197) AutoDown Grep processing
(8205) AutoDown return FALSE
(8217) AutoDown return TRUE
(8223) AutoDown return TRUE
(8231) AutoDown TransStartMacro failed
(8281) AutoDown return TRUE

　どれかあると思います。

　少数しか見つからない物は、たまたま出た物ということだと思います。

　特定の物が何回も連続して出てるのがあれば、それが犯人だと思います。

RE:36884 「定期受信できず」が頻繁に発生

もものしん さん　09/08/20 16:55

No.36884でご回答いただいた文字列は見当たりませんでした。
括弧の中の数字が異なる文字列は存在します。

もう一度dump.txtを記載します。pop3 serverからsign offした
直後からのログです。

16:30:08.026 (8210) tid=4940 ThreadExit 4940
16:30:08.027 (1289) Peek socket
16:30:08.028 (5914) IDTIMER_SOCKET_END received
16:30:08.028 (5976) IDTIMER_TRANS_START post
16:30:08.029 (5681) IDTIMER_TRANS_START kill
16:30:08.030 (8249) 終了
16:30:08.031 (8251) -
16:30:08.032 (5827) IDTIMER_TRANS_END set
16:30:08.032 (5910) IDTIMER_TRANS_END kill
16:30:08.033 ( 164) EndSilentDialog()
16:30:08.034 (6068) TransDlgProc() WM_DESTROY
16:30:08.035 ( 158) SilentDialogBox() exit
16:30:08.036 (6681) ProcessTransmitSub() ModelessDialog, TransDlgProc exit
16:30:08.037 (1950) RestoreTransmitTaskbarIcon()
16:30:08.037 (1793) SetReceivedTaskbarIcon Reset!
16:30:08.038 (1854) 本体アイコンを戻します。
16:30:08.040 (8249) 0通のメールを受信しました。
16:30:08.041 (6803) ProcessTransmitSub() exit
16:30:08.041 (7849) FreePatrol
16:30:08.042 (8539) LeaveTrans()
16:30:08.043 (8199) AutoDown exit
16:30:08.044 (14687) StartAutoDownTimer()
16:30:08.045 (14695) StartAutoDownTimer() set
16:30:13.373 (8062) WM_ACTIVATEAPP true
16:32:08.053 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:08.054 (1490) IsProcessExitable: ShowFrame
16:32:08.055 (1496) IsProcessExitable: editor > 1
16:32:08.056 (14707) StopAutoDownTimer()
16:32:08.057 (8072) CTrans::AutoDown()
16:32:08.057 (8093) AutoDown LastActivePopup exist
16:32:08.058 (1791) SetReceivedTaskbarIcon Set!
16:32:08.060 (8112) AutoDown return FALSE
16:32:08.061 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:32:18.053 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:18.054 (1490) IsProcessExitable: ShowFrame
16:32:18.055 (1496) IsProcessExitable: editor > 1
16:32:18.056 (14707) StopAutoDownTimer()
16:32:18.057 (8072) CTrans::AutoDown()
16:32:18.057 (8093) AutoDown LastActivePopup exist
16:32:18.058 (1791) SetReceivedTaskbarIcon Set!
16:32:18.059 (8112) AutoDown return FALSE
16:32:18.060 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:32:28.053 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:28.054 (1490) IsProcessExitable: ShowFrame
16:32:28.054 (1496) IsProcessExitable: editor > 1
16:32:28.055 (14707) StopAutoDownTimer()
16:32:28.056 (8072) CTrans::AutoDown()
16:32:28.057 (8093) AutoDown LastActivePopup exist
16:32:28.058 (1791) SetReceivedTaskbarIcon Set!
16:32:28.058 (8112) AutoDown return FALSE
16:32:28.059 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:32:38.068 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:38.069 (1490) IsProcessExitable: ShowFrame
16:32:38.070 (1496) IsProcessExitable: editor > 1
16:32:38.071 (14707) StopAutoDownTimer()
16:32:38.072 (8072) CTrans::AutoDown()
16:32:38.072 (8093) AutoDown LastActivePopup exist
16:32:38.073 (1791) SetReceivedTaskbarIcon Set!
16:32:38.074 (8112) AutoDown return FALSE
16:32:38.075 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:32:48.074 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:48.074 (1490) IsProcessExitable: ShowFrame
16:32:48.075 (1496) IsProcessExitable: editor > 1
16:32:48.076 (14707) StopAutoDownTimer()
16:32:48.077 (8072) CTrans::AutoDown()
16:32:48.077 (8093) AutoDown LastActivePopup exist
16:32:48.078 (1791) SetReceivedTaskbarIcon Set!
16:32:48.079 (8112) AutoDown return FALSE
16:32:48.080 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:32:58.084 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:32:58.085 (1490) IsProcessExitable: ShowFrame
16:32:58.086 (1496) IsProcessExitable: editor > 1
16:32:58.087 (14707) StopAutoDownTimer()
16:32:58.088 (8072) CTrans::AutoDown()
16:32:58.088 (8093) AutoDown LastActivePopup exist
16:32:58.089 (1791) SetReceivedTaskbarIcon Set!
16:32:58.090 (8112) AutoDown return FALSE
16:32:58.091 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:08.086 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:08.086 (1490) IsProcessExitable: ShowFrame
16:33:08.087 (1496) IsProcessExitable: editor > 1
16:33:08.088 (14707) StopAutoDownTimer()
16:33:08.089 (8072) CTrans::AutoDown()
16:33:08.090 (8093) AutoDown LastActivePopup exist
16:33:08.090 (1791) SetReceivedTaskbarIcon Set!
16:33:08.091 (8112) AutoDown return FALSE
16:33:08.092 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:18.101 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:18.101 (1490) IsProcessExitable: ShowFrame
16:33:18.102 (1496) IsProcessExitable: editor > 1
16:33:18.103 (14707) StopAutoDownTimer()
16:33:18.104 (8072) CTrans::AutoDown()
16:33:18.104 (8093) AutoDown LastActivePopup exist
16:33:18.105 (1791) SetReceivedTaskbarIcon Set!
16:33:18.106 (8112) AutoDown return FALSE
16:33:18.107 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:28.116 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:28.116 (1490) IsProcessExitable: ShowFrame
16:33:28.117 (1496) IsProcessExitable: editor > 1
16:33:28.118 (14707) StopAutoDownTimer()
16:33:28.119 (8072) CTrans::AutoDown()
16:33:28.119 (8093) AutoDown LastActivePopup exist
16:33:28.120 (1791) SetReceivedTaskbarIcon Set!
16:33:28.121 (8112) AutoDown return FALSE
16:33:28.122 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:38.120 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:38.121 (1490) IsProcessExitable: ShowFrame
16:33:38.121 (1496) IsProcessExitable: editor > 1
16:33:38.122 (14707) StopAutoDownTimer()
16:33:38.123 (8072) CTrans::AutoDown()
16:33:38.124 (8093) AutoDown LastActivePopup exist
16:33:38.125 (1791) SetReceivedTaskbarIcon Set!
16:33:38.126 (8112) AutoDown return FALSE
16:33:38.127 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:48.131 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:48.132 (1490) IsProcessExitable: ShowFrame
16:33:48.133 (1496) IsProcessExitable: editor > 1
16:33:48.134 (14707) StopAutoDownTimer()
16:33:48.135 (8072) CTrans::AutoDown()
16:33:48.135 (8093) AutoDown LastActivePopup exist
16:33:48.136 (1791) SetReceivedTaskbarIcon Set!
16:33:48.137 (8112) AutoDown return FALSE
16:33:48.138 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:33:58.132 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:33:58.132 (1490) IsProcessExitable: ShowFrame
16:33:58.133 (1496) IsProcessExitable: editor > 1
16:33:58.134 (14707) StopAutoDownTimer()
16:33:58.135 (8072) CTrans::AutoDown()
16:33:58.136 (8093) AutoDown LastActivePopup exist
16:33:58.136 (1791) SetReceivedTaskbarIcon Set!
16:33:58.137 (8112) AutoDown return FALSE
16:33:58.138 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:34:08.148 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:34:08.148 (1490) IsProcessExitable: ShowFrame
16:34:08.149 (1496) IsProcessExitable: editor > 1
16:34:08.150 (14707) StopAutoDownTimer()
16:34:08.151 (8072) CTrans::AutoDown()
16:34:08.151 (8093) AutoDown LastActivePopup exist
16:34:08.152 (1791) SetReceivedTaskbarIcon Set!
16:34:08.153 (8112) AutoDown return FALSE
16:34:08.154 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:34:18.148 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:34:18.148 (1490) IsProcessExitable: ShowFrame
16:34:18.149 (1496) IsProcessExitable: editor > 1
16:34:18.150 (14707) StopAutoDownTimer()
16:34:18.151 (8072) CTrans::AutoDown()
16:34:18.151 (8093) AutoDown LastActivePopup exist
16:34:18.152 (1791) SetReceivedTaskbarIcon Set!
16:34:18.153 (8112) AutoDown return FALSE
16:34:18.154 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:34:28.164 (7289) FrameWndProc() IDTIMER_AUTODOWN
16:34:28.164 (1490) IsProcessExitable: ShowFrame
16:34:28.165 (1496) IsProcessExitable: editor > 1
16:34:28.166 (14707) StopAutoDownTimer()
16:34:28.167 (8072) CTrans::AutoDown()
16:34:28.168 (8093) AutoDown LastActivePopup exist
16:34:28.169 (1791) SetReceivedTaskbarIcon Set!
16:34:28.170 (8112) AutoDown return FALSE
16:34:28.172 (7313) FrameWndProc() IDTIMER_AUTODOWN retry
16:34:28.846 (8062) WM_ACTIVATEAPP true
16:34:28.847 (7115) syscommand 0xF120
16:34:28.851 (1793) SetReceivedTaskbarIcon Reset!
16:34:28.852 (1854) 本体アイコンを戻します。
16:34:30.228 (7115) syscommand 0xF095
16:34:30.229 (7176) will DefWindowProc
16:34:31.638 (7180) DefWindowProc done nRet = 00000000
16:34:31.639 (12923) Cmd 40017

RE:36885 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/08/20 17:05

　括弧内の番号を間違えてしまってすみません。

　それで、問題となっている原因は、「AutoDown LastActivePopup exist」なん
ですけど、これは、秀丸メール本体ウィンドウが何かダイアログボックスを表示
している最中という意味になります。

　GetLastActivePopupって関数でウィンドウを取得してるんですけども…。

　何か心当たり無いですかね。

　とりあえず原因が分からないとまずいので、次のβ版にて、この「何か出てる
はずのウィンドウ」のタイトルをdump.txtに出すようにしてみます。

RE:36886 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/08/20 17:30

　V5.21β13をアップロードしました。これで一回またdump.txt取ってほしいで
す。

17:21:34.828 (8165) CTrans::AutoDown()
17:21:34.828 (8186) AutoDown LastActivePopup exist
17:21:34.828 (8193) 検索
17:21:34.828 (8195) #32770
17:21:34.828 (8212) visible=1 rect=(178,126)-(676,510) style=94C800C4 thread
=524 proc=1948 me=524,1948
17:21:34.828 (1832) SetReceivedTaskbarIcon 2
17:21:34.828 (8227) AutoDown return FALSE

　みたいな記録を出すようにしました。

　出てくる情報は、(8193)の所がウィンドウのタイトルバー、次の行がウィンド
ウのクラス名、その次の行が、visibleかどうか、ウィンドウ座標、スタイル、
スレッドID、プロセスIDです。

　me=xxx,xxxの所は、秀丸メール本体ウィンドウのスレッドIDおよびプロセスID
です。

　もしかして他のアプリケーションソフトが出してるウィンドウだとすると、プ
ロセスIDが秀丸メールとは別のIDになると思います。

　秀丸メールが出してるウィンドウだとしたら、プロセスIDが同じになるはずで
す。

　ということでお願いします。

http://hide.maruo.co.jp/software/bin2/hmmail521b13_signed.exe

RE:36887 「定期受信できず」が頻繁に発生

もものしん さん　09/08/24 17:04

お世話になります。

>　V5.21β13をアップロードしました。これで一回またdump.txt取ってほしいです。

現象が再現し、dump.txtを取得しました。以下にdump.txtを貼り付け
ます。

16:14:17.123 R +OK ******(メールアカウント) POP3 server signing off
16:14:17.126 (8290) tid=3156 ThreadExit 3156
16:14:17.127 (1307) Peek socket
16:14:17.128 (6007) IDTIMER_SOCKET_END received
16:14:17.128 (6069) IDTIMER_TRANS_START post
16:14:17.129 (5774) IDTIMER_TRANS_START kill
16:14:17.130 (8249) 終了
16:14:17.131 (8251) -
16:14:17.132 (5920) IDTIMER_TRANS_END set
16:14:17.133 (6003) IDTIMER_TRANS_END kill
16:14:17.134 ( 164) EndSilentDialog()
16:14:17.135 (6161) TransDlgProc() WM_DESTROY
16:14:17.136 ( 158) SilentDialogBox() exit
16:14:17.137 (6774) ProcessTransmitSub() ModelessDialog, TransDlgProc exit
16:14:17.137 (1989) RestoreTransmitTaskbarIcon()
16:14:17.138 (1832) SetReceivedTaskbarIcon 0
16:14:17.139 (1893) 本体アイコンを戻します。
16:14:17.141 (8249) 0通のメールを受信しました。
16:14:17.142 (6896) ProcessTransmitSub() exit
16:14:17.143 (7942) FreePatrol
16:14:17.144 (8654) LeaveTrans()
16:14:17.145 (8314) AutoDown exit
16:14:17.146 (14945) StartAutoDownTimer()
16:14:17.146 (14953) StartAutoDownTimer() set
16:16:17.158 (7371) FrameWndProc() IDTIMER_AUTODOWN
16:16:17.158 (1517) IsProcessExitable: ShowFrame
16:16:17.160 (14965) StopAutoDownTimer()
16:16:17.160 (8165) CTrans::AutoDown()
16:16:17.161 (8186) AutoDown LastActivePopup exist
16:16:17.162 (8193)
16:16:17.163 (8195) tooltips_class32
16:16:17.164 (8212) visible=0 rect=(212,245)-(362,262) style=84000002 thread
=4308 proc=4304 me=4308,4304
16:16:17.165 (1832) SetReceivedTaskbarIcon 2
16:16:17.166 (8227) AutoDown return FALSE
16:16:17.167 (7395) FrameWndProc() IDTIMER_AUTODOWN retry

----ここから----
16:16:27.160 (7371) FrameWndProc() IDTIMER_AUTODOWN
16:16:27.161 (1517) IsProcessExitable: ShowFrame
16:16:27.162 (14965) StopAutoDownTimer()
16:16:27.163 (8165) CTrans::AutoDown()
16:16:27.164 (8186) AutoDown LastActivePopup exist
16:16:27.165 (8193)
16:16:27.166 (8195) tooltips_class32
16:16:27.166 (8212) visible=0 rect=(212,245)-(362,262) style=84000002 thread
=4308 proc=4304 me=4308,4304
16:16:27.167 (1832) SetReceivedTaskbarIcon 2
16:16:27.168 (8227) AutoDown return FALSE
16:16:27.169 (7395) FrameWndProc() IDTIMER_AUTODOWN retry
----ここまで----

これ以降、上記のここから～ここまでの処理が10秒間隔で繰り返し
記録されます。

16:26:59.632 (7197) syscommand 0xF120
16:26:59.639 (1832) SetReceivedTaskbarIcon 0
16:26:59.640 (1893) 本体アイコンを戻します。
16:27:03.101 (7197) syscommand 0xF095
16:27:03.102 (7258) will DefWindowProc
16:27:05.047 (7262) DefWindowProc done nRet = 00000000
16:27:05.048 (13172) Cmd 40017
16:27:07.769 (7371) FrameWndProc() IDTIMER_AUTODOWN
16:27:07.903 (1517) IsProcessExitable: ShowFrame
16:27:07.905 (14965) StopAutoDownTimer()
16:27:08.069 (8165) CTrans::AutoDown()
16:27:08.131 (8186) AutoDown LastActivePopup exist
16:27:08.132 (8193) 全般的な設定
16:27:08.189 (8195) #32770
16:27:08.189 (8212) visible=1 rect=(99,98)-(707,514) style=94C800C4 thread=4
308 proc=4304 me=4308,4304
16:27:08.190 (1832) SetReceivedTaskbarIcon 2
16:27:08.192 (8227) AutoDown return FALSE
16:27:08.193 (7395) FrameWndProc() IDTIMER_AUTODOWN retry

なお、Process ExplorerでTuruKame.exeのPIDとTIDを確認
したところ、PIDは4304で、TIDの先頭は4308となってました。

以上、ご確認をお願いします。

RE:36905 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/08/24 17:49

　取っていただいたdump.txtによると、ウィンドウのクラス名が
tooltips_class32ということなので、いわゆるツールチップが出ているようです。
ですが、ウィンドウテキストは空っぽ、ウィンドウは非表示、ということで、画
面上は何も見えないことになります。

　こういう場合は、定期受信を止めないようにしないとダメだと思います。とい
うことでまた直します。

　先ほどV5.21正式版をアップロードしてしまった所なんですが、すみませんが
またβ版という形で出させていただきます。これで試してみてほしいです。

http://hide.maruo.co.jp/software/bin2/hmmail522b1_signed.exe

RE:36906 「定期受信できず」が頻繁に発生

もものしん さん　09/09/01 19:02

お世話になります。

>またβ版という形で出させていただきます。これで試してみてほしいです。
>
>http://hide.maruo.co.jp/software/bin2/hmmail522b1_signed.exe

ご提供いただいたβ版のモジュールを適用したところ、現象は
収まりました。また、定期受信そのものは問題なく動作して
おります。
対応、ありがとうございました。正式版へのfix、よろしくお願い
します。

RE:36938 「定期受信できず」が頻繁に発生

秀まるお2 さん　09/09/02 12:03

　とりあえずV5.22β1についてはV5.21から今回の修正しかしてないので、安定
動作するものとして使ってほしいです。

　正式版もなるべく早めにアップロードしたいと思います。