Ситуация: строю диал-ап на свою рабочую тачку (FreeBSD 6.3 + мопед GVC 33600 SF-1133 V/R16). Входящая линия одна. Перечитав кучу всяццких советов по этому поводу (в том числе и на https://www.opennet.ru/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 command

callback:
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      *   *