[Aug 31 16:14:00] VERBOSE[12467] logger.c: t203_expire
[Aug 31 16:14:00] VERBOSE[12467] logger.c: 
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > V(A)=87, V(S)=87, V(R)=104
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > [ 02 01 01 d1 ]
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > N(R): 104 P/F: 1
[Aug 31 16:14:00] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:00] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:00] VERBOSE[12467] logger.c: 
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < TEI: 0 State 8(Timer recovery)
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < V(A)=87, V(S)=87, V(R)=104
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < [ 02 01 01 af ]
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:00] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < N(R): 087 P/F: 1
[Aug 31 16:14:00] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:00] VERBOSE[12467] logger.c: -- Got ACK for N(S)=87 to (but not including) N(S)=87
[Aug 31 16:14:00] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:00] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:00] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:10] VERBOSE[12467] logger.c: 
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < V(A)=87, V(S)=87, V(R)=104
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < [ 00 01 01 af ]
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:10] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < N(R): 087 P/F: 1
[Aug 31 16:14:10] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:10] VERBOSE[12467] logger.c: 
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > V(A)=87, V(S)=87, V(R)=104
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > [ 00 01 01 d1 ]
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:10] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > N(R): 104 P/F: 1
[Aug 31 16:14:10] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:10] VERBOSE[12467] logger.c: -- Got ACK for N(S)=87 to (but not including) N(S)=87
[Aug 31 16:14:10] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:10] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:10] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:10] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < V(A)=87, V(S)=87, V(R)=104
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [ 00 01 d0 ae 08 02 0b 54 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < N(S): 104   0: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < N(R): 087   P: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Got ACK for N(S)=87 to (but not including) N(S)=87
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:12] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Making new call for cref 2900
[Aug 31 16:14:12] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: q931.c:8281 post_handle_q931_message: Call 2900 enters state 6 (Call Present).  Hold state: Idle
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > V(A)=87, V(S)=87, V(R)=105
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [ 00 01 01 d2 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > N(R): 105 P/F: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: q931.c:5552 q931_setup_ack: Call 2900 enters state 25 (Overlap Receiving).  Hold state: Idle
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=14
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Message Type: SETUP ACKNOWLEDGE (13)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=87, window is open V(A)=87 K=7
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > V(A)=87, V(S)=87, V(R)=105
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [ 02 01 ae d2 08 02 8b 54 0d 18 03 a9 83 8f 1e 02 81 82 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > N(S): 087   0: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > N(R): 105   P: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > 14 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=14
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Message Type: SETUP ACKNOWLEDGE (13)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [18 03 a9 83 8f]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >                       ChanSel: As indicated in following octets
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [1e 02 81 82]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >                               Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Stopping T203 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Message Type: PROGRESS (3)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=88, window is open V(A)=87 K=7
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > V(A)=87, V(S)=88, V(R)=105
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [ 02 01 b0 d2 08 02 8b 54 03 1e 02 81 88 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > N(S): 088   0: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > N(R): 105   P: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > 9 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Message Type: PROGRESS (3)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > [1e 02 81 88]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: >                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c:     -- Accepting overlap call from '1227' to '<unspecified>' on channel 0/15, span 2
[Aug 31 16:14:12] VERBOSE[15662] logger.c:     -- Starting simple switch on 'DAHDI/46-1'
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < V(A)=87, V(S)=89, V(R)=105
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [ 02 01 01 b0 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < N(R): 088 P/F: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Got ACK for N(S)=87 to (but not including) N(S)=88
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- ACKing N(S)=87, tx_queue head is N(S)=88 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Restarting T200 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: 
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < V(A)=88, V(S)=89, V(R)=105
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < [ 02 01 01 b2 ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < N(R): 089 P/F: 0
[Aug 31 16:14:12] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Got ACK for N(S)=88 to (but not including) N(S)=89
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- ACKing N(S)=88, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:12] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: 
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=105
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < [ 00 01 d2 b2 08 02 0b 54 7b 70 02 80 30 ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < N(S): 105   0: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < [70 02 80 30]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '0' ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:13] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:13] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: 
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=106
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > [ 00 01 01 d4 ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > N(R): 106 P/F: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:13] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: 
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=106
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < [ 00 01 d4 b2 08 02 0b 54 7b 70 02 80 30 ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < N(S): 106   0: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < [70 02 80 30]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '0' ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:13] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:13] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: 
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=107
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > [ 00 01 01 d6 ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > N(R): 107 P/F: 0
[Aug 31 16:14:13] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:13] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: 
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=107
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < [ 00 01 d6 b2 08 02 0b 54 7b 70 02 80 34 ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < N(S): 107   0: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < [70 02 80 34]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '4' ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:14] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:14] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: 
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=108
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > [ 00 01 01 d8 ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > N(R): 108 P/F: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:14] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: 
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=108
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < [ 00 01 d8 b2 08 02 0b 54 7b 70 02 80 39 ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < N(S): 108   0: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < [70 02 80 39]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '9' ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:14] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:14] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: 
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=109
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > [ 00 01 01 da ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > N(R): 109 P/F: 0
[Aug 31 16:14:14] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:14] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:17] VERBOSE[15662] logger.c:     -- Executing [0049@in-from-pbx-ext:1] Dial("DAHDI/46-1", "DAHDI/G1/0049") in new stack
[Aug 31 16:14:17] VERBOSE[15662] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
[Aug 31 16:14:17] VERBOSE[15662] logger.c:     -- Called G1/0049
[Aug 31 16:14:18] VERBOSE[15662] logger.c:     -- DAHDI/15-1 is making progress passing it to DAHDI/46-1
[Aug 31 16:14:18] DEBUG[15662] chan_dahdi.c: Received AST_CONTROL_PROGRESS on DAHDI/46-1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=109
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [ 00 01 da b2 08 02 0b 54 7b 70 02 80 35 ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(S): 109   0: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [70 02 80 35]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '5' ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:18] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=110
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > [ 00 01 01 dc ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > N(R): 110 P/F: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=110
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [ 00 01 dc b2 08 02 0b 54 7b 70 02 80 30 ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(S): 110   0: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [70 02 80 30]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '0' ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:18] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=111
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > [ 00 01 01 de ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > N(R): 111 P/F: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=111
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [ 00 01 de b2 08 02 0b 54 7b 70 02 80 30 ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(S): 111   0: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < [70 02 80 30]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '0' ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:18] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: 
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=112
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > [ 00 01 01 e0 ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > N(R): 112 P/F: 0
[Aug 31 16:14:18] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:18] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: 
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=112
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < [ 00 01 e0 b2 08 02 0b 54 7b 70 02 80 38 ]
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < N(S): 112   0: 0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < [70 02 80 38]
[Aug 31 16:14:19] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '8' ]
[Aug 31 16:14:19] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:19] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:19] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:19] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:19] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:19] VERBOSE[12467] logger.c: 
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=113
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > [ 00 01 01 e2 ]
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > N(R): 113 P/F: 0
[Aug 31 16:14:19] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:19] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: 
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=113
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < [ 00 01 e2 b2 08 02 0b 54 7b 70 02 80 34 ]
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < N(S): 113   0: 0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < [70 02 80 34]
[Aug 31 16:14:20] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '4' ]
[Aug 31 16:14:20] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:20] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:20] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:20] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:20] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:20] VERBOSE[12467] logger.c: 
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=114
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > [ 00 01 01 e4 ]
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > N(R): 114 P/F: 0
[Aug 31 16:14:20] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:20] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: 
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=114
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < [ 00 01 e4 b2 08 02 0b 54 7b 70 02 80 34 ]
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < N(S): 114   0: 0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < [70 02 80 34]
[Aug 31 16:14:21] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '4' ]
[Aug 31 16:14:21] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:21] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:21] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:21] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:21] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:21] VERBOSE[12467] logger.c: 
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=115
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > [ 00 01 01 e6 ]
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > N(R): 115 P/F: 0
[Aug 31 16:14:21] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:21] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: 
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=115
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < [ 00 01 e6 b2 08 02 0b 54 7b 70 02 80 35 ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < N(S): 115   0: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < [70 02 80 35]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '5' ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:22] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:22] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: 
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=116
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > [ 00 01 01 e8 ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > N(R): 116 P/F: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:22] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: 
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < V(A)=89, V(S)=89, V(R)=116
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < [ 00 01 e8 b2 08 02 0b 54 7b 70 02 80 38 ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < N(S): 116   0: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < N(R): 089   P: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent from originator)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Message Type: INFORMATION (123)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < [70 02 80 38]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: < Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '8' ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=89
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:22] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:22] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: -- Processing IE 112 (cs0, Called Party Number)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: 
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > V(A)=89, V(S)=89, V(R)=117
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > [ 00 01 01 ea ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > N(R): 117 P/F: 0
[Aug 31 16:14:22] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:22] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:27] VERBOSE[12466] logger.c:     -- Span 1: Channel 0/15 got hangup request, cause 1
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/15-1
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/15-1
[Aug 31 16:14:27] VERBOSE[15662] logger.c:     -- Hungup 'DAHDI/15-1'
[Aug 31 16:14:27] VERBOSE[15662] logger.c:   == Everyone is busy/congested at this time (1:0/0/1)
[Aug 31 16:14:27] VERBOSE[15662] logger.c:     -- Executing [0049@in-from-pbx-ext:2] Hangup("DAHDI/46-1", "") in new stack
[Aug 31 16:14:27] VERBOSE[15662] logger.c:   == Spawn extension (in-from-pbx-ext, 0049, 2) exited non-zero on 'DAHDI/46-1'
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/46-1
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Aug 31 16:14:27] VERBOSE[15662] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2900
[Aug 31 16:14:27] VERBOSE[15662] logger.c: q931.c:6594 __q931_hangup: ourstate Overlap Receiving, peerstate Overlap Sending, hold-state Idle
[Aug 31 16:14:27] VERBOSE[15662] logger.c: q931.c:6085 q931_release_complete: Call 2900 enters state 0 (Null).  Hold state: Idle
[Aug 31 16:14:27] VERBOSE[15662] logger.c: 
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > DL-DATA request
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: TEI=0 Transmitting N(S)=89, window is open V(A)=89 K=7
[Aug 31 16:14:27] VERBOSE[15662] logger.c: 
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > V(A)=89, V(S)=89, V(R)=117
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > [ 02 01 b2 ea 08 02 8b 54 5a 08 02 81 81 ]
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Informational frame:
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:27] VERBOSE[15662] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > N(S): 089   0: 0
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > N(R): 117   P: 0
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > 9 bytes of data
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > TEI=0 Call Ref: len= 2 (reference 2900/0xB54) (Sent to originator)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > [08 02 81 81]
[Aug 31 16:14:27] VERBOSE[15662] logger.c: > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:27] VERBOSE[15662] logger.c: >                  Ext: 1  Cause: Unallocated (unassigned) number (1), class = Normal Event (0) ]
[Aug 31 16:14:27] VERBOSE[15662] logger.c: -- Stopping T203 timer
[Aug 31 16:14:27] VERBOSE[15662] logger.c: -- Starting T200 timer
[Aug 31 16:14:27] VERBOSE[15662] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2900
[Aug 31 16:14:27] VERBOSE[15662] logger.c: q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:27] VERBOSE[15662] logger.c: Destroying call 0xb710a490, ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:27] DEBUG[15662] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/46-1
[Aug 31 16:14:27] VERBOSE[15662] logger.c:     -- Hungup 'DAHDI/46-1'
[Aug 31 16:14:27] VERBOSE[12467] logger.c: 
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < V(A)=89, V(S)=90, V(R)=117
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < [ 02 01 01 b4 ]
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:27] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < N(R): 090 P/F: 0
[Aug 31 16:14:27] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:27] VERBOSE[12467] logger.c: -- Got ACK for N(S)=89 to (but not including) N(S)=90
[Aug 31 16:14:27] VERBOSE[12467] logger.c: -- ACKing N(S)=89, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:27] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:27] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:27] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: 
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < V(A)=90, V(S)=90, V(R)=117
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < [ 00 01 ea b4 08 02 0b 55 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < N(S): 117   0: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < N(R): 090   P: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2901/0xB55) (Sent from originator)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Got ACK for N(S)=90 to (but not including) N(S)=90
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:35] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Making new call for cref 2901
[Aug 31 16:14:35] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: q931.c:8281 post_handle_q931_message: Call 2901 enters state 6 (Call Present).  Hold state: Idle
[Aug 31 16:14:35] VERBOSE[12467] logger.c: 
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > V(A)=90, V(S)=90, V(R)=118
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > [ 00 01 01 ec ]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > N(R): 118 P/F: 0
[Aug 31 16:14:35] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:35] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:35] WARNING[12467] chan_dahdi.c: Span 2: Got SETUP with duplicate call ptr.  Dropping call.
[Aug 31 16:14:35] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2901
[Aug 31 16:14:35] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Call Present, peerstate Call Initiated, hold-state Idle
[Aug 31 16:14:35] VERBOSE[12467] logger.c: q931.c:5783 q931_disconnect: Call 2901 enters state 11 (Disconnect Request).  Hold state: Idle
[Aug 31 16:14:39] VERBOSE[12467] logger.c: 
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < V(A)=90, V(S)=90, V(R)=118
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < [ 00 01 ec b4 08 02 0b 55 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < N(S): 118   0: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < N(R): 090   P: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2901/0xB55) (Sent from originator)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Got ACK for N(S)=90 to (but not including) N(S)=90
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:39] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:39] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: 
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > V(A)=90, V(S)=90, V(R)=119
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > [ 00 01 01 ee ]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > N(R): 119 P/F: 0
[Aug 31 16:14:39] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:39] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: 
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < V(A)=90, V(S)=90, V(R)=119
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < [ 00 01 ee b4 08 02 0b 55 45 08 02 80 90 ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < N(S): 119   0: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < N(R): 090   P: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < 9 bytes of data
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2901/0xB55) (Sent from originator)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Message Type: DISCONNECT (69)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < [08 02 80 90]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: <                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Got ACK for N(S)=90 to (but not including) N(S)=90
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:40] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:40] VERBOSE[12467] logger.c: Received message for call 0xb710a490 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Processing IE 8 (cs0, Cause)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: q931.c:8707 post_handle_q931_message: Call 2901 enters state 12 (Disconnect Indication).  Hold state: Idle
[Aug 31 16:14:40] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2901
[Aug 31 16:14:40] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Disconnect Indication, peerstate Disconnect Request, hold-state Idle
[Aug 31 16:14:40] VERBOSE[12467] logger.c: q931.c:5703 q931_release: Call 2901 enters state 19 (Release Request).  Hold state: Idle
[Aug 31 16:14:40] VERBOSE[12467] logger.c: 
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2901/0xB55) (Sent to originator)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=90, window is open V(A)=90 K=7
[Aug 31 16:14:40] VERBOSE[12467] logger.c: 
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > V(A)=90, V(S)=90, V(R)=120
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=1
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > [ 02 01 b4 f0 08 02 8b 55 5a 08 02 81 90 ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > N(S): 090   0: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > N(R): 120   P: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > 9 bytes of data
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2901/0xB55) (Sent to originator)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > [08 02 81 90]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Stopping T203 timer
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:40] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: 
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < V(A)=90, V(S)=91, V(R)=120
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < [ 02 01 01 b6 ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < N(R): 091 P/F: 0
[Aug 31 16:14:40] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Got ACK for N(S)=90 to (but not including) N(S)=91
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- ACKing N(S)=90, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:40] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:40] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: 
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < V(A)=91, V(S)=91, V(R)=120
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < [ 00 01 f0 b6 08 02 0b 56 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < N(S): 120   0: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < N(R): 091   P: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2902/0xB56) (Sent from originator)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Got ACK for N(S)=91 to (but not including) N(S)=91
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:42] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Making new call for cref 2902
[Aug 31 16:14:42] VERBOSE[12467] logger.c: Received message for call 0xb7100780 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:8281 post_handle_q931_message: Call 2902 enters state 6 (Call Present).  Hold state: Idle
[Aug 31 16:14:42] VERBOSE[12467] logger.c: 
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > V(A)=91, V(S)=91, V(R)=121
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > [ 00 01 01 f2 ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > N(R): 121 P/F: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:42] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:42] DEBUG[12467] chan_dahdi.c: Span 2: SETUP requested unavailable channel 0/15.  Attempting to renegotiate.
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2902
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Call Present, peerstate Call Initiated, hold-state Idle
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:6085 q931_release_complete: Call 2902 enters state 0 (Null).  Hold state: Idle
[Aug 31 16:14:42] VERBOSE[12467] logger.c: 
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2902/0xB56) (Sent to originator)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=91, window is open V(A)=91 K=7
[Aug 31 16:14:42] VERBOSE[12467] logger.c: 
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > V(A)=91, V(S)=91, V(R)=121
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > [ 02 01 b6 f2 08 02 8b 56 5a 08 02 81 ac ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > N(S): 091   0: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > N(R): 121   P: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > 9 bytes of data
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2902/0xB56) (Sent to originator)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > [08 02 81 ac]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: >                  Ext: 1  Cause: Requested channel not available (44), class = Network Congestion (resource unavailable) (2) ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Stopping T203 timer
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2902
[Aug 31 16:14:42] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:42] VERBOSE[12467] logger.c: Destroying call 0xb7100780, ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:42] VERBOSE[12467] logger.c: 
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < V(A)=91, V(S)=92, V(R)=121
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < [ 02 01 01 b8 ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < N(R): 092 P/F: 0
[Aug 31 16:14:42] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Got ACK for N(S)=91 to (but not including) N(S)=92
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- ACKing N(S)=91, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:42] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:42] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: 
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < V(A)=92, V(S)=92, V(R)=121
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < [ 00 01 f2 b8 08 02 0b 57 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < N(S): 121   0: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < N(R): 092   P: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2903/0xB57) (Sent from originator)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Got ACK for N(S)=92 to (but not including) N(S)=92
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:46] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Making new call for cref 2903
[Aug 31 16:14:46] VERBOSE[12467] logger.c: Received message for call 0xb7100780 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:8281 post_handle_q931_message: Call 2903 enters state 6 (Call Present).  Hold state: Idle
[Aug 31 16:14:46] VERBOSE[12467] logger.c: 
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > V(A)=92, V(S)=92, V(R)=122
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > [ 00 01 01 f4 ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > N(R): 122 P/F: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:46] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:46] DEBUG[12467] chan_dahdi.c: Span 2: SETUP requested unavailable channel 0/15.  Attempting to renegotiate.
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2903
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Call Present, peerstate Call Initiated, hold-state Idle
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:6085 q931_release_complete: Call 2903 enters state 0 (Null).  Hold state: Idle
[Aug 31 16:14:46] VERBOSE[12467] logger.c: 
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2903/0xB57) (Sent to originator)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=92, window is open V(A)=92 K=7
[Aug 31 16:14:46] VERBOSE[12467] logger.c: 
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > V(A)=92, V(S)=92, V(R)=122
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > [ 02 01 b8 f4 08 02 8b 57 5a 08 02 81 ac ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > N(S): 092   0: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > N(R): 122   P: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > 9 bytes of data
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2903/0xB57) (Sent to originator)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > [08 02 81 ac]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: >                  Ext: 1  Cause: Requested channel not available (44), class = Network Congestion (resource unavailable) (2) ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Stopping T203 timer
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2903
[Aug 31 16:14:46] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:46] VERBOSE[12467] logger.c: Destroying call 0xb7100780, ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:46] VERBOSE[12467] logger.c: 
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < V(A)=92, V(S)=93, V(R)=122
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < [ 02 01 01 ba ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < N(R): 093 P/F: 0
[Aug 31 16:14:46] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Got ACK for N(S)=92 to (but not including) N(S)=93
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- ACKing N(S)=92, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:46] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:46] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: 
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < V(A)=93, V(S)=93, V(R)=122
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < [ 00 01 f4 ba 08 02 0b 58 05 04 03 80 90 a3 18 03 a9 83 8f 6c 06 00 80 31 32 32 37 ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Informational frame:
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < N(S): 122   0: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < N(R): 093   P: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < 23 bytes of data
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Protocol Discriminator: Q.931 (8)  len=23
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < TEI=0 Call Ref: len= 2 (reference 2904/0xB58) (Sent from originator)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Message Type: SETUP (5)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < [04 03 80 90 a3]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                                User information layer 1: A-Law (35)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < [18 03 a9 83 8f]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                       ChanSel: As indicated in following octets
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                       Ext: 1  Channel: 15 Type: NET]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < [6c 06 00 80 31 32 32 37]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <                           Presentation: Presentation permitted, user number not screened (0)  '1227' ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Got ACK for N(S)=93 to (but not including) N(S)=93
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- T200 requested to stop when not started
[Aug 31 16:14:52] VERBOSE[12467] logger.c: T203 requested to start without stopping first
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Making new call for cref 2904
[Aug 31 16:14:52] VERBOSE[12467] logger.c: Received message for call 0xb7100780 on link 0x81d4c6c TEI/SAPI 0/0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Processing Q.931 Call Setup
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Processing IE 24 (cs0, Channel Identification)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:8281 post_handle_q931_message: Call 2904 enters state 6 (Call Present).  Hold state: Idle
[Aug 31 16:14:52] VERBOSE[12467] logger.c: 
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > V(A)=93, V(S)=93, V(R)=123
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > [ 00 01 01 f6 ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Supervisory frame:
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 0 EA: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > N(R): 123 P/F: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > 0 bytes of data
[Aug 31 16:14:52] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0
[Aug 31 16:14:52] DEBUG[12467] chan_dahdi.c: Span 2: SETUP requested unavailable channel 0/15.  Attempting to renegotiate.
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2904
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Call Present, peerstate Call Initiated, hold-state Idle
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:6085 q931_release_complete: Call 2904 enters state 0 (Null).  Hold state: Idle
[Aug 31 16:14:52] VERBOSE[12467] logger.c: 
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > DL-DATA request
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2904/0xB58) (Sent to originator)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: TEI=0 Transmitting N(S)=93, window is open V(A)=93 K=7
[Aug 31 16:14:52] VERBOSE[12467] logger.c: 
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > V(A)=93, V(S)=93, V(R)=123
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > T200_id=0, N200=3, T203_id=16384
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > [ 02 01 ba f6 08 02 8b 58 5a 08 02 81 ac ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Informational frame:
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: >  TEI: 000        EA: 1
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > N(S): 093   0: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > N(R): 123   P: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > 9 bytes of data
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > TEI=0 Call Ref: len= 2 (reference 2904/0xB58) (Sent to originator)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Message Type: RELEASE COMPLETE (90)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > [08 02 81 ac]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: >                  Ext: 1  Cause: Requested channel not available (44), class = Network Congestion (resource unavailable) (2) ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Stopping T203 timer
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Starting T200 timer
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:6837 q931_hangup: Hangup other cref:2904
[Aug 31 16:14:52] VERBOSE[12467] logger.c: q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:52] VERBOSE[12467] logger.c: Destroying call 0xb7100780, ourstate Null, peerstate Null, hold-state Idle
[Aug 31 16:14:52] VERBOSE[12467] logger.c: 
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < TEI: 0 State 7(Multi-frame established)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < V(A)=93, V(S)=94, V(R)=123
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < T200_id=16384, N200=3, T203_id=0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < [ 02 01 01 bc ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Supervisory frame:
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < SAPI: 00  C/R: 1 EA: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: <  TEI: 000        EA: 1
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < N(R): 094 P/F: 0
[Aug 31 16:14:52] VERBOSE[12467] logger.c: < 0 bytes of data
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Got ACK for N(S)=93 to (but not including) N(S)=94
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- ACKing N(S)=93, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Stopping T200 timer
[Aug 31 16:14:52] VERBOSE[12467] logger.c: -- Starting T203 timer
[Aug 31 16:14:52] VERBOSE[12467] logger.c: Done handling message for SAPI/TEI=0/0