[netatalk-ja:0491] Re: MacOS9で接続解除になる

[murayama]

HATさん

やはり、でてました。
フラグが4回で切れるところをログをmaxdebugでとれました。
と、同時に、OS9で切断される事も再現しました。

ただ、現象が発生するのは1回だけで、接続が切れてから、もう一度接続してか
らフォルダを開くと、問題なく開けました。その後も同じです。

OS9の他のマシンで試しても、やはり1度目はだめで、2度目は表示できました。
ちょっとMacOS9のディレクトリ関係の挙動の事は分からないので憶測ですが、そ
のフォルダの内容をキャッシュしてしまうとOKのようです。

ログは大量にでるので、どれに注目すべきかわかりませんが、alermやerrorはで
ていません。

Aug 28 12:38:44.654196 afpd[30330] {afp_dsi.c:321} (error:AFPDaemon):
afp_alarm: child timed out, entering disconnected state
とでていますが、処理というか

Aug 28 12:38:44.663499 afpd[30330] {ad_open.c:531} (debug:ad):
ad_valid_header_osx("/home/kyoyu/PBOX/00p-box1/._23008"): BEGIN
Aug 28 12:38:44.672076 afpd[30330] {ad_open.c:562} (debug:ad):
ad_valid_header_osx("/home/kyoyu/PBOX/00p-box1/._23008"): END: 0

このようなログは以後また続いています。

処理をしている途中にフラグが4つになってクライアント切れているように思い
ます。
想像ですが、なんとなく
OS9がウィンドウを開き、処理を待つ
サーバが表示用の処理をする
サーバがクライアントを待つ
フラグが繰り返され、接続が切れる
サーバの処理が終わる
OS9再接続、フォルダ内の項目が取得できる

というような気がします。

OS9がそのディレクトリの情報をいつまでキャッシュしているのかは
ちょっとわかりませんでしたが、再起動したりしても表示は問題なさそうでした。

全然関係ないログもちょこっと入っていますが、
大量のファイルがあるのが、/home/kyoyu/PBOX/00p-box1/という場所です。

お時間があれば、ログを見ていただけると助かります。

https://www.dropbox.com/s/kjs3mpm4w56alab/afp.log?dl=0

> HATさん
> 
> maxdebugではHATさんのおっしゃるとおり
> 
> Aug 13 18:03:46.741248 afpd[27685] {afp_dsi.c:297} (maxdebug:AFPDaemon):
> alarm: tickles: 1, flags: -|-|-|-|-|-|-|-|-
> Aug 13 18:03:46.741298 afpd[27685] {afp_dsi.c:328} (debug:AFPDaemon):
> afp_alarm: sending DSI tickle
> Aug 13 18:04:11.371036 afpd[27685] {afp_dsi.c:589} (debug:AFPDaemon):
> DSI: client tickle
> 
> とtickleでgrepしてみるとでているようです。ただ最近はログレベルを落として
> いたので、これが今も出ているか調べてみようと思います
> 
> 村山
> 
> On 2015/08/26 23:58, HAT wrote:
>> HATです。
>>
>>>> afp_alarm: child timed out, entering disconnected state
>>
>> このエラーなんですが、書いてあるとおり、タイムアウトです。
>> AFP(正確にはDSI)プロトコルでは、特に通信がない場合、30秒間隔でtickleという
>> コマンドを送って相手が生きているかどうか確認します。
>> これを4回繰り返しても返答がない場合、相手が死んでいる、または通信が切れて
>> いると判断してタイムアウトします。
>> つまり、
>> 30秒 * 4回 = 2分
>> なので、2分でタイムアウトです。
>>
>> [netatalk-ja:0481] MacOS9で接続解除になる
>> において、
>>
>>> 一度フォルダを開いてしまうと問題がないのですが、
>>> 最初にフォルダを開く時や、フォルダを開かずにそこに書類をコピーしようとす
>>> るとしばらく応答がなくなり、ダイアログで「接続が解除されました」出てしま
>>> います。
>>
>> と書かれていますが、「しばらく応答がなくなり」とは2分だと思われます。
>> NetatalkがMacOS9に4回tickleを送っても返事がないので、Netatalk側が
>> 通信を切っているのでしょう。
>>
>> なぜMacOS9が返答しなくなるのかは謎ですが。
>>