[Sep 20 11:32:42] VERBOSE[28252] chan_iax2.c:     -- Accepting AUTHENTICATED call from 10.111.1.145:
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [1260@callfromsitea:1] GotoIf("IAX2/ohtrunk-2285", "0?2:3") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Goto (callfromsitea,1260,3)
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [1260@callfromsitea:3] Set("IAX2/ohtrunk-2285", "CALLERID(all)=<8118237>") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [1260@callfromsitea:4] Goto("IAX2/ohtrunk-2285", "out-to-pbx,1260,1") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Goto (out-to-pbx,1260,1)
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [1260@out-to-pbx:1] Macro("IAX2/ohtrunk-2285", "dialpbx,1260,INTTRUNK,user@pbx.invalid") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:1] Set("IAX2/ohtrunk-2285", "CALLERID(num)=8118237") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:2] Set("IAX2/ohtrunk-2285", "DB(lastcaller/1260)=8118237") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:3] Set("IAX2/ohtrunk-2285", "DB(lastcallertime/1260)=1316514762") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:4] Set("IAX2/ohtrunk-2285", "ExtenEmail=user@pbx.invalid") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:5] GotoIf("IAX2/ohtrunk-2285", "0?9:6") in new stack
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Goto (macro-dialpbx,s,6)
[Sep 20 11:32:42] VERBOSE[28531] pbx.c:     -- Executing [s@macro-dialpbx:6] Dial("IAX2/ohtrunk-2285", "DAHDI/g2/1260,20,r") in new stack
[Sep 20 11:32:42] DEBUG[28531] sig_pri.c: sig_pri_request 32
[Sep 20 11:32:42] DEBUG[28531] sig_pri.c: CALLER NAME:  NUM: 8118237
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 -- Making new call for cref 32821
[Sep 20 11:32:42] VERBOSE[28531] sig_pri.c:     -- Requested transfer capability: 0x00 - SPEECH
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > DL-DATA request
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=38
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Message Type: SETUP (5)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 TEI=0 Transmitting N(S)=65, window is open V(A)=65 K=7
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > V(A)=65, V(S)=65, V(R)=103
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [ 02 01 82 ce 08 02 00 35 05 04 03 80 90 a3 18 03 a9 83 81 6c 09 00 83 38 31 31 38 32 33 37 70 05 80 31 32 36 30 74 03 00 00 80 ]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Informational frame:
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > N(S): 065   0: 0
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > N(R): 103   P: 0
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > 38 bytes of data
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=38
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Message Type: SETUP (5)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [04 03 80 90 a3]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                                User information layer 1: A-Law (35)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [18 03 a9 83 81]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                       ChanSel: As indicated in following octets
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                       Ext: 1  Channel: 1 Type: NET]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [6c 09 00 83 38 31 31 38 32 33 37]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Calling Number (len=11) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                           Presentation: Presentation allowed of network provided number (3)  '8118237' ]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [70 05 80 31 32 36 30]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Called Number (len= 7) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '1260' ]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [74 03 00 00 80]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Redirecting Number (len= 5) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                               Ext: 0  Presentation: Presentation permitted, user number not screened (0)
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                               Ext: 1  Reason: Unknown (0)  '' ]
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 -- Stopping T203 timer
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 -- Starting T200 timer
[Sep 20 11:32:42] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 q931.c:6036 q931_setup: Call 32821 enters state 1 (Call Initiated).  Hold state: Idle
[Sep 20 11:32:42] VERBOSE[28531] app_dial.c:     -- Called DAHDI/g2/1260
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=65, V(S)=66, V(R)=103
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=16384, N200=3, T203_id=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 02 01 01 84 ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Supervisory frame:
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 066 P/F: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 0 bytes of data
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=65 to (but not including) N(S)=66
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- ACKing N(S)=65, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Stopping T200 timer
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=66, V(S)=66, V(R)=103
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 00 01 ce 84 08 02 80 35 02 18 03 a9 83 81 ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Informational frame:
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(S): 103   0: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 066   P: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 10 bytes of data
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Protocol Discriminator: Q.931 (8)  len=10
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent to originator)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Message Type: CALL PROCEEDING (2)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [18 03 a9 83 81]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <                       ChanSel: As indicated in following octets
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <                       Ext: 1  Channel: 1 Type: NET]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=66 to (but not including) N(S)=66
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- T200 requested to stop when not started
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 T203 requested to start without stopping first
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Received message for call 0xb660c3f8 on link 0x872cf1c TEI/SAPI 0/0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Processing IE 24 (cs0, Channel Identification)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:8454 post_handle_q931_message: Call 32821 enters state 3 (Outgoing Call Proceeding).  Hold state: Idle
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > V(A)=66, V(S)=66, V(R)=104
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > [ 00 01 01 d0 ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Supervisory frame:
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > N(R): 104 P/F: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > 0 bytes of data
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0
[Sep 20 11:32:42] VERBOSE[28262] sig_pri.c: Span: 2 Processing event: PRI_EVENT_PROCEEDING
[Sep 20 11:32:42] VERBOSE[28531] app_dial.c:     -- DAHDI/i2/1260-3b is proceeding passing it to IAX2/ohtrunk-2285
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=66, V(S)=66, V(R)=104
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 00 01 d0 84 08 02 80 35 01 ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Informational frame:
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(S): 104   0: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 066   P: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 5 bytes of data
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Protocol Discriminator: Q.931 (8)  len=5
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent to originator)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Message Type: ALERTING (1)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=66 to (but not including) N(S)=66
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- T200 requested to stop when not started
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 T203 requested to start without stopping first
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Received message for call 0xb660c3f8 on link 0x872cf1c TEI/SAPI 0/0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:8319 post_handle_q931_message: Call 32821 enters state 4 (Call Delivered).  Hold state: Idle
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > V(A)=66, V(S)=66, V(R)=105
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > [ 00 01 01 d2 ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Supervisory frame:
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > N(R): 105 P/F: 0
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > 0 bytes of data
[Sep 20 11:32:42] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0
[Sep 20 11:32:42] VERBOSE[28262] sig_pri.c: Span: 2 Processing event: PRI_EVENT_RINGING
[Sep 20 11:32:42] VERBOSE[28531] app_dial.c:     -- DAHDI/i2/1260-3b is ringing
[Sep 20 11:32:46] DEBUG[28531] sig_pri.c: sig_pri_hangup 32
[Sep 20 11:32:46] DEBUG[28531] sig_pri.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 q931.c:6837 q931_hangup: Hangup other cref:32821
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 q931.c:6594 __q931_hangup: ourstate Call Delivered, peerstate Call Received, hold-state Idle
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 q931.c:5783 q931_disconnect: Call 32821 enters state 11 (Disconnect Request).  Hold state: Idle
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > DL-DATA request
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=9
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Message Type: DISCONNECT (69)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 TEI=0 Transmitting N(S)=66, window is open V(A)=66 K=7
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > V(A)=66, V(S)=66, V(R)=105
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [ 02 01 84 d2 08 02 00 35 45 08 02 81 90 ]
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Informational frame:
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > N(S): 066   0: 0
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > N(R): 105   P: 0
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > 9 bytes of data
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=9
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Message Type: DISCONNECT (69)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > [08 02 81 90]
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 -- Stopping T203 timer
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c: PRI Span: 2 -- Starting T200 timer
[Sep 20 11:32:46] VERBOSE[28531] chan_dahdi.c:     -- Hungup 'DAHDI/i2/1260-3b'
[Sep 20 11:32:46] VERBOSE[28531] app_macro.c:   == Spawn extension (macro-dialpbx, s, 6) exited non-zero on 'IAX2/ohtrunk-2285' in macro 'dialpbx'
[Sep 20 11:32:46] VERBOSE[28531] pbx.c:   == Spawn extension (out-to-pbx, 1260, 1) exited non-zero on 'IAX2/ohtrunk-2285'
[Sep 20 11:32:46] VERBOSE[28531] chan_iax2.c:     -- Hungup 'IAX2/ohtrunk-2285'
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=66, V(S)=67, V(R)=105
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=16384, N200=3, T203_id=0
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 02 01 01 86 ]
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Supervisory frame:
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 067 P/F: 0
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 0 bytes of data
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=66 to (but not including) N(S)=67
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- ACKing N(S)=66, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Stopping T200 timer
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:46] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=67, V(S)=67, V(R)=105
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 00 01 d2 86 08 02 80 35 4d ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Informational frame:
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(S): 105   0: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 067   P: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 5 bytes of data
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Protocol Discriminator: Q.931 (8)  len=5
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent to originator)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Message Type: RELEASE (77)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=67 to (but not including) N(S)=67
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- T200 requested to stop when not started
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 T203 requested to start without stopping first
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Received message for call 0xb660c3f8 on link 0x872cf1c TEI/SAPI 0/0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:8620 post_handle_q931_message: Call 32821 enters state 0 (Null).  Hold state: Idle
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > V(A)=67, V(S)=67, V(R)=106
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > [ 00 01 01 d4 ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Supervisory frame:
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 0 EA: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > N(R): 106 P/F: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > 0 bytes of data
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0
[Sep 20 11:32:47] VERBOSE[28262] sig_pri.c: Span: 2 Processing event: PRI_EVENT_HANGUP
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:6837 q931_hangup: Hangup other cref:32821
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:6594 __q931_hangup: ourstate Null, peerstate Release Request, hold-state Idle
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > DL-DATA request
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=9
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Message Type: RELEASE COMPLETE (90)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 TEI=0 Transmitting N(S)=67, window is open V(A)=67 K=7
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > V(A)=67, V(S)=67, V(R)=106
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > T200_id=0, N200=3, T203_id=16384
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > [ 02 01 86 d4 08 02 00 35 5a 08 02 81 90 ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Informational frame:
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 >  TEI: 000        EA: 1
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > N(S): 067   0: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > N(R): 106   P: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > 9 bytes of data
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 (8)  len=9
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > TEI=0 Call Ref: len= 2 (reference 53/0x35) (Sent from originator)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Message Type: RELEASE COMPLETE (90)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > [08 02 81 90]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Stopping T203 timer
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T200 timer
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:6837 q931_hangup: Hangup other cref:32821
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Destroying call 0xb660c3f8, ourstate Null, peerstate Null, hold-state Idle
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < TEI: 0 State 7(Multi-frame established)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < V(A)=67, V(S)=68, V(R)=106
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < T200_id=16384, N200=3, T203_id=0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < [ 02 01 01 88 ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Supervisory frame:
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < SAPI: 00  C/R: 1 EA: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 <  TEI: 000        EA: 1
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < N(R): 068 P/F: 0
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 < 0 bytes of data
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Got ACK for N(S)=67 to (but not including) N(S)=68
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- ACKing N(S)=67, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted)
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Stopping T200 timer
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 -- Starting T203 timer
[Sep 20 11:32:47] VERBOSE[28262] chan_dahdi.c: PRI Span: 2 Done handling message for SAPI/TEI=0/0