[debian-users:50468] /dev/ttyS?をモニタする方法

["Nanbu works Inc." <office@xxxxxxxxx>]

河野@南部製作所です
御世話になります

シリアルラインをモニタする方法を探しています。

先のデータ通信カード、W05Kを使った通信の問題について、
問題があったときに迅速に切断／再接続する方法をとることで
解決の方法を探っています。

Debian Etchにおいて、
/etc/ppp/options
内の
lcp-echo-interval を 2〜5
lcp-echo-failure 3〜5
あたりにすると、希望の動作に近くなります。

しかし、pppdの再接続動作が大体は迅速に行われるのですが、時々妙に
時間のかかるときがあります。

再接続動作は通常十数秒程度なのですが、30秒以上かかるようになります。
これのトラブルシュートをしていますが、kdebug 7 に、 debug on
にしても特にログに原因特定できるようなものはなさそうです。

実際に/dev/ttyS1ポートに何が流れているか知りたいのですが、
シリアルラインをモニタする方法はありませんでしょうか？

資料

(1)wvdial の表示

接続開始
--> Initializing modem.
--> Sending: ATZ
--> Sending: ATQ0
OK
・
・
省略
・
・
--> [06][08][18][0b][06][08]
--> Script /etc/ppp/ip-up run successful
--> Default route Ok.
--> Nameserver (DNS) Ok.
--> Connected... Press Ctrl-C to disconnect
--> pppd: X
--> [06][08][18][0b][06][08]
接続中

LCP failを検出、切断動作
--> pppd: X
--> [06][08][18][0b][06][08]
--> pppd: X
--> [06][08][18][0b][06][08]
--> Connect time 5.2 minutes.
--> pppd: X
--> [06][08][18][0b][06][08]
--> pppd: X
--> [06][08][18][0b][06][08]
--> Script /etc/ppp/ip-down started
--> pppd: X
--> [06][08][18][0b][06][08]
--> Terminate Request (Message: "Peer not responding" )
--> pppd: X
--> [06][08][18][0b][06][08]
--> pppd: X
--> [06][08][18][0b][06][08]
--> Terminate Request (Message: "Peer not responding" )
--> pppd: X
--> [06][08][18][0b][06][08]
--> pppd: X
--> [06][08][18][0b][06][08]

ここで時間がかかる
--> pppd: X
--> [06][08][18][0b][06][08]
--> Disconnecting at Mon May  5 21:22:18 2008
--> The PPP daemon has died: A modem hung up the phone (exit code = 16)
--> man pppd explains pppd error codes in more detail.
--> Try again and look into /var/log/messages and the wvdial and pppd
man pages for more information.
--> Auto Reconnect will be attempted in 5 seconds
--> Initializing modem.
--> Sending: ATZ
--> Sending: ATQ0
OK
--> Re-Sending: ATZ
OK
・
・
省略

(2)/var/log/debugの記録

時間のかかるとき
May  5 21:21:50 debian pppd[15489]: Script /etc/ppp/ip-down started (pid 17448)
May  5 21:21:50 debian pppd[15489]: sent [LCP TermReq id=0x2 "Peer not
responding"]
May  5 21:21:50 debian pppd[15489]: Script /etc/ppp/ip-down finished
(pid 17448), status = 0x0
May  5 21:21:53 debian pppd[15489]: sent [LCP TermReq id=0x3 "Peer not
responding"]
May  5 21:22:32 debian pppd[17720]: using channel 20
May  5 21:22:32 debian pppd[17720]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <magic 0xc84b33a4> <pcomp> <accomp>]
May  5 21:22:32 debian pppd[17720]: rcvd [LCP ConfAck id=0x1 <asyncmap
0x0> <magic 0xc84b33a4> <pcomp> <accomp>]
May  5 21:22:34 debian pppd[17720]: rcvd [LCP ConfReq id=0x2 <mru
1500> <asyncmap 0x0> <auth chap MD5> <magic 0x689122f8> <pcomp>
<accomp>]
May  5 21:22:34 debian pppd[17720]: sent [LCP ConfAck id=0x2 <mru
1500> <asyncmap 0x0> <auth chap MD5> <magic 0x689122f8> <pcomp>
<accomp>]
May  5 21:22:34 debian pppd[17720]: sent [LCP EchoReq id=0x0 magic=0xc84b33a4]
May  5 21:22:34 debian pppd[17720]: rcvd [CHAP Challenge id=0x1
<ddae4242a0d701c1faf1ca8be308839253d78a57d6fe7d89db220379>, name =
"ID"]
May  5 21:22:34 debian pppd[17720]: sent [CHAP Response id=0x1
<fc2e02ee2787950dd033ace8450fa474>, name = "au@xxxxxxxxxxxx"]
May  5 21:22:34 debian pppd[17720]: rcvd [LCP EchoRep id=0x0 magic=0x689122f8]
May  5 21:22:34 debian pppd[17720]: rcvd [CHAP Success id=0x1 ""]

時間のかからないとき
May  6 16:02:58 debian pppd[13433]: Script /etc/ppp/ip-down started (pid 16199)
May  6 16:02:58 debian pppd[13433]: Waiting for 1 child processes...
May  6 16:02:58 debian pppd[13433]:   script /etc/ppp/ip-down, pid 16199
May  6 16:02:58 debian pppd[13433]: Script /etc/ppp/ip-down finished
(pid 16199), status = 0x0
May  6 16:03:08 debian pppd[16292]: using channel 26
May  6 16:03:08 debian pppd[16292]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <magic 0x796d9cbe> <pcomp> <accomp>]
May  6 16:03:08 debian pppd[16292]: rcvd [LCP ConfAck id=0x1 <asyncmap
0x0> <magic 0x796d9cbe> <pcomp> <accomp>]
May  6 16:03:11 debian pppd[16292]: rcvd [LCP ConfReq id=0x2 <mru
1500> <asyncmap 0x0> <auth chap MD5> <magic 0x68097241> <pcomp>
<accomp>]
May  6 16:03:11 debian pppd[16292]: sent [LCP ConfAck id=0x2 <mru
1500> <asyncmap 0x0> <auth chap MD5> <magic 0x68097241> <pcomp>
<accomp>]
May  6 16:03:11 debian pppd[16292]: sent [LCP EchoReq id=0x0 magic=0x796d9cbe]
May  6 16:03:11 debian pppd[16292]: rcvd [CHAP Challenge id=0x1
<afb5cf041249e8015a9dde0d82c0e1846628a945>, name = "ID"]
May  6 16:03:11 debian pppd[16292]: sent [CHAP Response id=0x1
<fc31c383d8983a3d4159d64cabfc991e>, name = "au@xxxxxxxxxxxx"]
May  6 16:03:11 debian pppd[16292]: rcvd [LCP EchoRep id=0x0 magic=0x68097241]
May  6 16:03:11 debian pppd[16292]: rcvd [CHAP Success id=0x1 ""]


-- 
-------------------------------
Yoshimasa Kawano
Nanbu works Inc.
TEL:+81-88-626-6075
南部製作所　河野悦昌
088-626-6075