Verbindungsabbrüche

[Proteus]

ich betreibe eine Enterprise Telefonanlage auf Asterisk Basis an 2 Standorten, welche über eine IAX-2 Verbindung zusammenhängen.

Am Standort 1 befindet sich ein Primärmultiplexer, dieser ist mit Server1 über eine Junghanns SingleE1 verbunden.


1. Problembeschreibung:

Telefonieren klappt im großen und ganzen, jedoch kommt es zeitweilig zu Verbindungsabbrüchen in den Gesprächen. Diese sowohl SIP <-> SIP als auch Standortübergreifend und ins Festnetz. Die Abbrüche treten zu beliebigen Zeiten auf und in den Logs verhält es sich wie ein normal beendetes Gespräch.

Hier Ausschnitte aus den Logs:

Nicht funktionierend:

Aug  1 14:39:47 VERBOSE[6207] logger.c:     -- Executing Dial("SIP/373-086fe108", "SIP/301||tr") in new stack
Aug  1 14:39:47 DEBUG[6207] chan_sip.c: Setting NAT on RTP to 524288
Aug  1 14:39:47 DEBUG[6207] chan_sip.c: Outgoing Call for 301
Aug  1 14:39:47 VERBOSE[6207] logger.c:     -- Called 301
Aug  1 14:39:47 VERBOSE[6207] logger.c:     -- SIP/301-08731118 is ringing
Aug  1 14:39:48 VERBOSE[6207] logger.c:     -- SIP/301-08731118 is ringing
Aug  1 14:39:49 VERBOSE[6207] logger.c:     -- SIP/301-08731118 is ringing
Aug  1 14:39:49 VERBOSE[6207] logger.c:     -- SIP/301-08731118 answered SIP/373-086fe108
Aug  1 14:40:07 DEBUG[6207] channel.c: Didn't get a frame from channel: SIP/301-08731118
Aug  1 14:40:07 DEBUG[6207] channel.c: Bridge stops bridging channels SIP/373-086fe108 and SIP/301-08731118
Aug  1 14:40:07 DEBUG[6207] chan_sip.c: update_call_counter(301) - decrement call limit counter
Aug  1 14:40:07 DEBUG[6207] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Aug  1 14:40:07 VERBOSE[6207] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/373-086fe108' in macro 'dial'
Aug  1 14:40:07 VERBOSE[6207] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/373-086fe108' in macro 'exten-vm'
Aug  1 14:40:07 VERBOSE[6207] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/373-086fe108'
Aug  1 14:40:07 VERBOSE[6207] logger.c:     -- Executing Macro("SIP/373-086fe108", "hangupcall") in new stack

Funktionierend:

Aug  1 09:46:26 VERBOSE[30701] logger.c:     -- Executing Dial("SIP/360-086cd228", "SIP/365||tr") in new stack
Aug  1 09:46:26 DEBUG[30701] chan_sip.c: Setting NAT on RTP to 524288
Aug  1 09:46:26 DEBUG[30701] chan_sip.c: Outgoing Call for 365
Aug  1 09:46:26 VERBOSE[30701] logger.c:     -- Called 365
Aug  1 09:46:27 VERBOSE[30701] logger.c:     -- SIP/365-08731118 is ringing
Aug  1 09:46:27 VERBOSE[30701] logger.c:     -- SIP/365-08731118 is ringing
Aug  1 09:46:27 VERBOSE[30701] logger.c:     -- SIP/365-08731118 is ringing
Aug  1 09:46:28 VERBOSE[30701] logger.c:     -- SIP/365-08731118 answered SIP/360-086cd228
Aug  1 09:47:37 DEBUG[30701] channel.c: Didn't get a frame from channel: SIP/365-08731118
Aug  1 09:47:37 DEBUG[30701] channel.c: Bridge stops bridging channels SIP/360-086cd228 and SIP/365-08731118
Aug  1 09:47:37 DEBUG[30701] chan_sip.c: update_call_counter(365) - decrement call limit counter
Aug  1 09:47:37 DEBUG[30701] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Aug  1 09:47:37 VERBOSE[30701] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/360-086cd228' in macro 'dial'
Aug  1 09:47:37 VERBOSE[30701] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/360-086cd228' in macro 'exten-vm'
Aug  1 09:47:37 VERBOSE[30701] logger.c:   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/360-086cd228'
Aug  1 09:47:37 VERBOSE[30701] logger.c:     -- Executing Macro("SIP/360-086cd228", "hangupcall") in new stack

2. Problembeschreibung:
Teilweise können keine Telefonate ins Festnetz über den Multiplexer getätigt werden, da der Channel belegt zu sein scheint.

Aug  3 10:18:36 VERBOSE[31392] logger.c:     -- Executing NoOp("IAX2/iax2-connect-5", "RDNIS:") in new stack
Aug  3 10:18:36 VERBOSE[31392] logger.c:     -- Executing NoOp("IAX2/iax2-connect-5", "CID: 768887335|EXTEN: 05734922463") in new stack
Aug  3 10:18:36 VERBOSE[31392] logger.c:     -- Executing Dial("IAX2/iax2-connect-5", "Zap/g1/05734922463|60") in new stack
Aug  3 10:18:36 VERBOSE[31392] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Aug  3 10:18:36 VERBOSE[31392] logger.c:     -- Called g1/05734922463
Aug  3 10:18:39 VERBOSE[31392] logger.c:     -- Zap/2-1 is circuit-busy
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Hangup: channel: 2 index = 0, normal = 13, callwait = -1, thirdcall = -1
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: disabled echo cancellation on channel 2
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Updated conferencing on 2, with 0 conference users
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
Aug  3 10:18:39 DEBUG[31392] chan_zap.c: disabled echo cancellation on channel 2
Aug  3 10:18:39 VERBOSE[31392] logger.c:     -- Hungup 'Zap/2-1'
Aug  3 10:18:39 VERBOSE[31392] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)
Aug  3 10:18:39 DEBUG[31392] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
Aug  3 10:18:49 WARNING[31392] pbx.c: Timeout, but no rule 't' in context 'iax-incoming'
Aug  3 10:18:49 DEBUG[31392] chan_iax2.c: We're hanging up IAX2/iax2-connect-5 with cause 41 now...
Aug  3 10:18:49 VERBOSE[31392] logger.c:     -- Hungup 'IAX2/iax2-connect-5'
[code]

Zu gegebenem Zeitpunkt waren jedoch noch mindestens 25 freie Leitungen auf der Karte verfügbar.
Ich konnte beobachten, dass teilweise die Leitung nach Gesprächsaufbau gewechselt wird, aber so können nicht 2 Teilnehmer gleichzeitig telefonieren.

Konfigurationen:
zapata.conf
[code]
[channels]
usecallerid=yes
hidecallerid=no
internationalprefix=00
nationalprefix=0
usecallingpres=yes
busydetect=no
callprogress=no
callwaitingcallerid=yes
echotraining=no
echocancel=yes
echocancelwhenbridged=no
overlapdial=yes
immediate=no
callerid=asreceived
language=de
rxgain=0.0
txgain=0.0
switchtype=euroisdn
pridialplan=local
prilocaldialplan=local
signalling=pri_cpe
group=1
context=pri1
channel => 1-15,17-31

zaptel.conf

loadzone=nl
defaultzone=nl
span=1,2,3,ccs,hdb3,crc4
alaw=1-31
bchan=1-15
dchan=16
bchan=17-31

 

[Thomas007]

Was sagt denn zap show channels zu diesem Zeitpunkt?

War Zap/2-1 wirklich belegt?

Ich meine da gab es mal einen bug mit bestimmten pri_cause der Channels blockiert hat.

 

[Proteus]

Ich gehe davon aus, leider konnte ich das Problem noch nicht nachstellen, da es irgendwie beliebig auftritt so dass ich ein "zap show channels" zum richtigen Zeitpunkt abzusetzten.

Jetzt doch...

nein, er war scheinbar nicht belegt.

 

[Thomas007]

also nicht so dramatisch.

Bau doch nach einem BUSY die Ausgabe von zap show channels in ein logfile ein und wähle noch mal neu, evtl. mit ZAP/G1 um einen funktionierenden Channel zu erwischen.

EDIT: ein pri debug wäre auch sinnvoll

 

[Proteus]

Hier mal ein intense debug von so nem Anruf...

Aug  3 15:24:54 VERBOSE[822] logger.c:     -- Executing NoOp("IAX2/iax2-connect-3", "RDNIS:") in new stack
Aug  3 15:24:54 VERBOSE[822] logger.c:     -- Executing NoOp("IAX2/iax2-connect-3", "CID: 768YYYYYY|EXTEN: 051115XXXXXX") in new stack
Aug  3 15:24:54 VERBOSE[822] logger.c:     -- Executing Dial("IAX2/iax2-connect-3", "Zap/g1/051115XXXXXX|60") in new stack
Aug  3 15:24:54 VERBOSE[822] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 
> [ 00 01 ca a8 08 02 01 23 05 04 03 80 90 a3 18 03 a9 83 82 6c 0b 41 80 37 36 38 38 38 37 33 39 39 70 0d c1 30 35 31 31 31 35 31 37 37 30 32 30 ]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 
> Informational frame:
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > N(S): 101   0: 0
> N(R): 084   P: 0
> 43 bytes of data
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 Stopping T_203 timer
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 Starting T_200 timer
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Protocol Discriminator: Q.931 (8)  len=43
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Call Ref: len= 2 (reference 291/0x123) (Originator)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Message type: SETUP (5)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > [04 03 80 90 a3]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                              Ext: 1  User information layer 1: A-Law (35)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > [18 03 a9 83 82]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                        ChanSel: Reserved
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                       Ext: 1  Channel: 2 ]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > [6c 0b 41 80 37 36 38 38 38 37 33 39 39]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Calling Number (len=13) [ Ext: 0  TON: Subscriber Number (4)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 >                           Presentation: Presentation permitted, user number not screened (0) '768YYYYYY' ]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > [70 0d c1 30 35 31 31 31 35 31 37 37 30 32 30]
Aug  3 15:24:54 VERBOSE[822] logger.c: 1 > Called Number (len=15) [ Ext: 1  TON: Subscriber Number (4)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '051115XXXXXX' ]
Aug  3 15:24:54 VERBOSE[822] logger.c:     -- Called g1/051115XXXXXX
Aug  3 15:24:54 DEBUG[29081] channel.c: Avoiding initial deadlock for 'Zap/2-1'
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 
< [ 00 01 01 cc ]
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 
< Supervisory frame:
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 102 P/F: 0
< 0 bytes of data
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 100 to (but not including) 102
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 -- ACKing packet 101, new txqueue is -1 (-1 means empty)
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:54 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
< [ 02 01 a8 cc 08 02 81 23 0d 18 03 a9 83 82 ]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
< Informational frame:
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < N(S): 084   0: 0
< N(R): 102   P: 0
< 10 bytes of data
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 101 to (but not including) 102
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Stopping T203 counter since we got an ACK
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < Protocol Discriminator: Q.931 (8)  len=10
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < Call Ref: len= 2 (reference 291/0x123) (Terminator)
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < Message type: SETUP ACKNOWLEDGE (13)
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < [18 03 a9 83 82]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 <                        ChanSel: Reserved
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 <                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 <                       Ext: 1  Channel: 2 ]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 Sending Receiver Ready (85)
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
> [ 02 01 01 aa ]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
> Supervisory frame:
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 > SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 085 P/F: 0
> 0 bytes of data
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:55 DEBUG[29092] chan_iax2.c: Ooh, voice format changed to 4
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 T203 counter expired, sending RR and scheduling T203 again
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 Sending Receiver Ready (85)
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
> [ 00 01 01 ab ]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
> Supervisory frame:
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 > SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 085 P/F: 1
> 0 bytes of data
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
< [ 00 01 01 cd ]
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 
< Supervisory frame:
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 102 P/F: 1
< 0 bytes of data
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 101 to (but not including) 102
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Stopping T203 counter since we got an ACK
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Got RR response to our frame
Aug  3 15:24:55 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< [ 02 01 aa cc 08 02 81 23 45 08 02 82 a9 1e 02 82 88 ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< Informational frame:
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < N(S): 085   0: 0
< N(R): 102   P: 0
< 13 bytes of data
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 101 to (but not including) 102
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Stopping T203 counter since we got an ACK
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Protocol Discriminator: Q.931 (8)  len=13
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Call Ref: len= 2 (reference 291/0x123) (Terminator)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Message type: DISCONNECT (69)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < [08 02 82 a9]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Public network serving the local user (2)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 <                  Ext: 1  Cause: Temporary failure (41), class = Network Congestion (2) ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < [1e 02 82 88]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Public network serving the local user (2)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 <                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 Sending Receiver Ready (86)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
> [ 02 01 01 ac ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
> Supervisory frame:
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 > SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 086 P/F: 0
> 0 bytes of data
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c:     -- Channel 0/2, span 1 got hangup request
Aug  3 15:24:58 VERBOSE[29090] logger.c:     -- Channel 0/2, span 1 received AOC-E charging 0 units
Aug  3 15:24:58 VERBOSE[822] logger.c:     -- Zap/2-1 is circuit-busy
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Hangup: channel: 2 index = 0, normal = 13, callwait = -1, thirdcall = -1
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 
> [ 00 01 cc ac 08 02 01 23 4d 08 02 81 a9 ]
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 
> Informational frame:
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > N(S): 102   0: 0
> N(R): 086   P: 0
> 9 bytes of data
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 Stopping T_203 timer
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 Starting T_200 timer
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > Protocol Discriminator: Q.931 (8)  len=9
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > Call Ref: len= 2 (reference 291/0x123) (Originator)
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > Message type: RELEASE (77)
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > [08 02 81 a9]
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Private network serving the local user (1)
Aug  3 15:24:58 VERBOSE[822] logger.c: 1 >                  Ext: 1  Cause: Temporary failure (41), class = Network Congestion (2) ]
Aug  3 15:24:58 DEBUG[822] chan_zap.c: disabled echo cancellation on channel 2
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Updated conferencing on 2, with 0 conference users
Aug  3 15:24:58 DEBUG[822] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
Aug  3 15:24:58 DEBUG[822] chan_zap.c: disabled echo cancellation on channel 2
Aug  3 15:24:58 VERBOSE[822] logger.c:     -- Hungup 'Zap/2-1'
Aug  3 15:24:58 VERBOSE[822] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)
Aug  3 15:24:58 DEBUG[822] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< [ 00 01 01 ce ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< Supervisory frame:
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 103 P/F: 0
< 0 bytes of data
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 101 to (but not including) 103
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- ACKing packet 102, new txqueue is -1 (-1 means empty)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< [ 02 01 ac ce 08 02 81 23 5a ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
< Informational frame:
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < N(S): 086   0: 0
< N(R): 103   P: 0
< 5 bytes of data
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 102 to (but not including) 103
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Stopping T203 counter since we got an ACK
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Protocol Discriminator: Q.931 (8)  len=5
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Call Ref: len= 2 (reference 291/0x123) (Terminator)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 < Message type: RELEASE COMPLETE (90)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 Sending Receiver Ready (87)
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
> [ 02 01 01 ae ]
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 
> Supervisory frame:
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 > SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 087 P/F: 0
> 0 bytes of data
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:58 VERBOSE[29090] logger.c: 1 -- Restarting T203 counter
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 
< [ 02 01 ae ce 08 02 80 00 4e 18 03 a1 83 94 79 01 80 ]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 
< Informational frame:
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < N(S): 087   0: 0
< N(R): 103   P: 0
< 13 bytes of data
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 -- ACKing all packets from 102 to (but not including) 103
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 -- Since there was nothing left, stopping T200 counter
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 -- Stopping T203 counter since we got an ACK
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 -- Nothing left, starting T203 counter
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < Protocol Discriminator: Q.931 (8)  len=13
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < Call Ref: len= 2 (reference 0/0x0) (Terminator)
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < Message type: RESTART ACKNOWLEDGE (78)
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < [18 03 a1 83 94]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 <                        ChanSel: Reserved
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 <                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 <                       Ext: 1  Channel: 20 ]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < [79 01 80]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 < Restart Indentifier (len= 3) [ Ext: 1  Spare: 0  Resetting Indicated Channel (0) ]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 Sending Receiver Ready (88)
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 
> [ 02 01 01 b0 ]
Aug  3 15:24:59 VERBOSE[29090] logger.c: 1 
> Supervisory frame:

Ich benutze ja Zap/g1