Ситуация: строю диал-ап на свою рабочую тачку (FreeBSD 6.3 + мопед GVC 33600 SF-1133 V/R16). Входящая линия одна. Перечитав кучу всяццких советов по этому поводу (в том числе и на http://www.opennet.me/base/modem/freebsd_as.txt.html), дозвон сделал. А вот callback никак не получается. Сервер перезванивает на клиента (винда + мопед GVC 33600 SF-1133 V/R16), тот снимает трубу и тут же идет сброс...
вот, сопссно, лог:
Mar 4 16:21:40 info2 ppp[2174]: Phase: Using interface: tun0
Mar 4 16:21:40 info2 ppp[2174]: Phase: deflink: Created in closed state
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: enable pap passwdauth
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: enable proxy
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set ifaddr 192.168.1.3 192.168.1.241-192.168.1.244
Mar 4 16:21:40 info2 ppp[2174]: tun0: IPCP: Selected IP address 192.168.1.241
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: accept dns
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set callback cbcp
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set cbcp
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set log +cbcp
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set redial 5 1
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set device /dev/cuad0
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set speed 57600
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set dial TIMEOUT 10 "" AT OK-AT-OK ATDT\T CONNECT
Mar 4 16:21:40 info2 ppp[2174]: tun0: Command: callback: set timeout 900
Mar 4 16:21:40 info2 ppp[2174]: tun0: Phase: PPP Started (direct mode).
Mar 4 16:21:40 info2 ppp[2174]: tun0: Phase: bundle: Establish
Mar 4 16:21:40 info2 ppp[2174]: tun0: Phase: deflink: closed -> opening
Mar 4 16:21:40 info2 ppp[2174]: tun0: Phase: deflink: Connected!
Mar 4 16:21:40 info2 ppp[2174]: tun0: Phase: deflink: opening -> carrier
Mar 4 16:21:41 info2 ppp[2174]: tun0: Phase: deflink: /dev/cuad0: CD detected
Mar 4 16:21:41 info2 ppp[2174]: tun0: Phase: deflink: carrier -> lcp
Mar 4 16:21:41 info2 ppp[2174]: tun0: LCP: FSM: Using "deflink" as a transport
Mar 4 16:21:41 info2 ppp[2174]: tun0: LCP: deflink: State change Initial --> Closed
Mar 4 16:21:41 info2 ppp[2174]: tun0: LCP: deflink: State change Closed --> Stopped
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: deflink: LayerStart
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: ACFCOMP[2]
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: PROTOCOMP[2]
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: ACCMAP[6] 0x00000000
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: MRU[4] 1500
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: MAGICNUM[6] 0x6f34e573
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Mar 4 16:21:42 info2 ppp[2174]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: deflink: RecvConfigReq(3) state = Req-Sent
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: ACCMAP[6] 0x000a0000
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: MAGICNUM[6] 0x01a6622e
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: PROTOCOMP[2]
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: ACFCOMP[2]
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: CALLBACK[3] CBCP
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: deflink: SendConfigAck(3) state = Req-Sent
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: ACCMAP[6] 0x000a0000
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: MAGICNUM[6] 0x01a6622e
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: PROTOCOMP[2]
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: ACFCOMP[2]
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: CALLBACK[3] CBCP
Mar 4 16:21:43 info2 ppp[2174]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: deflink: SendConfigReq(1) state = Ack-Sent
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: ACFCOMP[2]
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: PROTOCOMP[2]
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: ACCMAP[6] 0x00000000
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: MRU[4] 1500
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: MAGICNUM[6] 0x6f34e573
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: ACFCOMP[2]
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: PROTOCOMP[2]
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: ACCMAP[6] 0x00000000
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: MRU[4] 1500
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: MAGICNUM[6] 0x6f34e573
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Mar 4 16:21:45 info2 ppp[2174]: tun0: LCP: deflink: LayerUp
Mar 4 16:21:45 info2 ppp[2174]: tun0: Phase: bundle: Authenticate
Mar 4 16:21:45 info2 ppp[2174]: tun0: Phase: deflink: his = none, mine = PAP
Mar 4 16:21:45 info2 ppp[2174]: tun0: Phase: Pap Input: REQUEST (hata1)
Mar 4 16:21:45 info2 ppp[2174]: tun0: Phase: Pap Output: SUCCESS
Mar 4 16:21:45 info2 ppp[2174]: tun0: Phase: deflink: lcp -> cbcp
Mar 4 16:21:45 info2 ppp[2174]: tun0: CBCP: deflink: SendReq(0) state = closed
Mar 4 16:21:45 info2 ppp[2174]: tun0: CBCP: TYPE Server-spec
Mar 4 16:21:45 info2 ppp[2174]: tun0: CBCP: DELAY 0
Mar 4 16:21:45 info2 ppp[2174]: tun0: CBCP: deflink: State change closed --> req-sent
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: deflink: RecvResponse(0) state = req-sent
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: TYPE Server-spec
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: DELAY 15
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: deflink: SendAck(0) state = req-sent
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: TYPE Server-spec
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: DELAY 15
Mar 4 16:21:49 info2 ppp[2174]: tun0: CBCP: deflink: State change req-sent --> ack-sent
Mar 4 16:21:49 info2 ppp[2174]: tun0: LCP: deflink: RecvTerminateReq(4) state = Opened
Mar 4 16:21:49 info2 ppp[2174]: tun0: LCP: deflink: LayerDown
Mar 4 16:21:49 info2 ppp[2174]: tun0: Phase: deflink: CBCP: Will dial back on 7571848
Mar 4 16:21:49 info2 ppp[2174]: tun0: LCP: deflink: SendTerminateAck(4) state = Opened
Mar 4 16:21:49 info2 ppp[2174]: tun0: LCP: deflink: State change Opened --> Stopping
Mar 4 16:21:49 info2 ppp[2174]: tun0: Phase: deflink: cbcp -> lcp
Mar 4 16:21:50 info2 ppp[2174]: tun0: Phase: deflink: Carrier lost
Mar 4 16:21:50 info2 ppp[2174]: tun0: LCP: deflink: State change Stopping --> Starting
Mar 4 16:21:50 info2 ppp[2174]: tun0: LCP: deflink: LayerFinish
Mar 4 16:21:50 info2 ppp[2174]: tun0: LCP: deflink: State change Starting --> Initial
Mar 4 16:21:50 info2 ppp[2174]: tun0: Phase: deflink: Disconnected!
Mar 4 16:21:50 info2 ppp[2174]: tun0: Phase: deflink: Connect time: 10 secs: 225 octets in, 233 octets out
Mar 4 16:21:50 info2 ppp[2174]: tun0: Phase: deflink: 11 packets in, 7 packets out
Mar 4 16:21:50 info2 ppp[2174]: tun0: Phase: total 45 bytes/sec, peak 76 bytes/sec on Tue Mar 4 16:21:46 2008
Mar 4 16:21:50 info2 ppp[2195]: tun0: Phase: Call peer back on 7571848
Mar 4 16:21:50 info2 ppp[2195]: tun0: CBCP: deflink: State change ack-sent --> closed
Mar 4 16:21:50 info2 ppp[2195]: tun0: Phase: deflink: lcp -> opening
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: deflink: Redial timer expired.
Mar 4 16:22:05 info2 ppp[2195]: tun0: Phase: deflink: Connected!
Mar 4 16:22:05 info2 ppp[2195]: tun0: Phase: deflink: opening -> dial
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Phone: 7571848
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: deflink: Dial attempt 1 of 1
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Send: AT^M
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Expect(10): OK
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Received: AT^M^M
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Received: OK^M
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Send: ATDT7571848^M
Mar 4 16:22:05 info2 ppp[2195]: tun0: Chat: Expect(10): CONNECT
Mar 4 16:22:15 info2 ppp[2195]: tun0: Chat: Expect timeout
Mar 4 16:22:15 info2 ppp[2195]: tun0: Warning: Chat script failed
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: deflink: dial -> hangup
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: deflink: Disconnected!
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: deflink: Connect time: 10 secs: 0 octets in, 0 octets out
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: deflink: 11 packets in, 7 packets out
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Tue Mar 4 16:22:05 2008
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: deflink: hangup -> closed
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: bundle: Dead
Mar 4 16:22:15 info2 ppp[2195]: tun0: Phase: PPP Terminated (normal).Конфиги
/etc/ppp/ppp.conf
default:
set log Phase Chat LCP IPCP CCP tun commandcallback:
enable pap passwdauth
enable proxy
set ifaddr 192.168.1.3 192.168.1.241-192.168.1.244
accept dns
set callback cbcp
set cbcp
set log +cbcp
set redial 5 1
set device /dev/cuad0
set speed 57600
###дальше я заморачивался с разными скриптами
set dial "TIMEOUT 10 \"\" AT OK-AT-OK ATDT\\T CONNECT"
#set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 2 \"\" ATE1Q0M0 OK-AT-OK\\dATDT\\T TIMEOUT 40 CONNECT"
#set dial "TIMEOUT 10 "" AT OK-AT-OK ATDT\T TIMEOUT 10 CONNECT"
set timeout 900/etc/ppp/ppp.secret
# Authname Authkey Peer's IP address Label Callback
user1 passw 192.168.1.251 * 1234567
user passw2 192.168.1.252 * *
грабли найдены и обезврежены :lol:
а стояли они здесь:
/etc/ppp/ppp.conf
set dial "TIMEOUT 10 \"\" AT OK-AT-OK ATDT\\T CONNECT"
при таком раскладе не успевал обрабатываться скрипт и связь рвалась.
правим скриптик и получаем:
/etc/ppp/ppp.conf
set dial "TIMEOUT 10 \"\" AT OK-AT-OK ATDT\\T TIMEOUT 60 CONNECT"
[OT]и жили они долго и счастливо пока не расконнектил их провайдер[/OT]думаю, можно закрывать тему...)))