pri intensive debug span 1
Enabled EXTENSIVE debugging on span 1
  == Using SIP RTP CoS mark 5
    -- Executing [0123xxxxx9@p-Obert:1] Gosub("SIP/209-b6e03230", "trucar-fora,0123xxxxx9,1(r5,123xxxxx9)") in new stack
    -- Executing [0123xxxxx9@trucar-fora:1] NoOp("SIP/209-b6e03230", ""fora"") in new stack
    -- Executing [0123xxxxx9@trucar-fora:2] GotoIf("SIP/209-b6e03230", "0?noanswer") in new stack
    -- Executing [0123xxxxx9@trucar-fora:3] GotoIf("SIP/209-b6e03230", "0?cid") in new stack
    -- Executing [0123xxxxx9@trucar-fora:4] Set("SIP/209-b6e03230", "OUT=") in new stack
    -- Executing [0123xxxxx9@trucar-fora:5] Goto("SIP/209-b6e03230", "next") in new stack
    -- Goto (trucar-fora,0123xxxxx9,7)
    -- Executing [0123xxxxx9@trucar-fora:7] Set("SIP/209-b6e03230", "DB(209/RepeatDial)=0123xxxxx9") in new stack
    -- Executing [0123xxxxx9@trucar-fora:8] Set("SIP/209-b6e03230", "CALLERID(num)=") in new stack
    -- Executing [0123xxxxx9@trucar-fora:9] Dial("SIP/209-b6e03230", "DAHDI/r5/123xxxxx9") in new stack
-- Making new call for cr 32772
    -- Requested transfer capability: 0x00 - SPEECH

> [ 00 01 0e 0c 08 01 04 05 04 03 80 90 a3 18 01 89 6c 02 00 81 70 0a 80 36 36 35 38 37 30 35 36 39 a1 ]

> Informational frame:
> SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
> N(S): 007   0: 0
> N(R): 006   P: 0
> 29 bytes of data
Stopping T_203 timer
Starting T_200 timer
-- Restarting T200 timer
> Protocol Discriminator: Q.931 (8)  len=29
> Call Ref: len= 1 (reference 4/0x4) (Originator)
> Message type: SETUP (5)
> [04 03 80 90 a3]
> Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
>                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
>                                User information layer 1: A-Law (35)
> [18 01 89]
> Channel ID (len= 3) [ Ext: 1  IntID: Implicit  Other  Spare: 0  Exclusive  Dchan: 0
>                        ChanSel: B1 channel
                         ]
> [6c 02 00 81]
> Calling Number (len= 4) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
>                           Presentation: Presentation permitted, user number passed network screening (1)  '' ]
> [70 0a 80 36 36 35 38 37 30 35 36 39]
> Called Number (len=12) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '123xxxxx9' ]
> [a1]
> Sending Complete (len= 1)
q931.c:3128 q931_setup: call 32772 on channel 1 enters state 1 (Call Initiated)
    -- Called r5/123xxxxx9
OptiCentral*CLI> 
< [ 00 01 01 10 ]
OptiCentral*CLI> 
< Supervisory frame:
< SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
< Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 008 P/F: 0
< 0 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 6 to (but not including) 8
-- ACKing packet 7, new txqueue is -1 (-1 means empty)
-- Since there was nothing left, stopping T200 counter
-- Nothing left, starting T203 counter
-- Restarting T203 timer
OptiCentral*CLI> 
< [ 02 01 0c 10 08 01 84 02 18 01 89 ]
OptiCentral*CLI> 
< Informational frame:
< SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
< N(S): 006   0: 0
< N(R): 008   P: 0
< 7 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 8
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8)  len=7
< Call Ref: len= 1 (reference 4/0x4) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 01 89]CLI> 
< Channel ID (len= 3) [ Ext: 1  IntID: Implicit  Other  Spare: 0  Exclusive  Dchan: 0
<                        ChanSel: B1 channel
                         ]
-- Processing IE 24 (cs0, Channel Identification)
q931.c:3677 q931_receive: call 32772 on channel 1 enters state 3 (Outgoing call  Proceeding)
Sending Receiver Ready (7)
OptiCentral*CLI> 
> [ 02 01 01 0e ]
OptiCentral*CLI> 
> Supervisory frame:
> SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 007 P/F: 0
> 0 bytes of data
-- Restarting T203 timer
    -- DAHDI/1-1 is proceeding passing it to SIP/209-b6e03230
OptiCentral*CLI> 
< [ 02 01 0e 10 08 01 84 01 1e 02 84 88 ]

< Informational frame:
< SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
< N(S): 007   0: 0
< N(R): 008   P: 0
< 8 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 8
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8)  len=8
< Call Ref: len= 1 (reference 4/0x4) (Terminator)
< Message type: ALERTING (1)
< [1e 02 84 88]
< Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Public network serving the remote user (4)
<                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
-- Processing IE 30 (cs0, Progress Indicator)
q931.c:3590 q931_receive: call 32772 on channel 1 enters state 4 (Call Delivered)
Sending Receiver Ready (8)

> [ 02 01 01 10 ]

> Supervisory frame:
> SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 008 P/F: 0
> 0 bytes of data
-- Restarting T203 timer
    -- DAHDI/1-1 is ringing
OptiCentral*CLI> 
< [ 02 01 10 10 08 01 84 07 29 06 09 06 19 09 12 12 4c 0b 21 83 36 36 35 38 37 30 35 36 39 14 ]

< Informational frame:
< SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
< N(S): 008   0: 0
< N(R): 008   P: 0
< 26 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 8
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8)  len=26
 Call Ref: len= 1 (reference 4/0x4) (Terminator)
< Message type: CONNECT (7)
< [29 06 09 06 19 09 12 12]
< Time Date (len= 8) [ 09-06-25 09:18:18 ]
< [4c 0b 21 83 36 36 35 38 37 30 35 36 39]
< Connected Number (len=13) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
<                             Ext: 1  Presentation: Presentation allowed of network provided number (3)  '123xxxxx9' ]
< [14 42 00 00 00 00 00 00 00 00 00 07 00 00 00 00 00 00 00 d8 f7 c5 b6 bc 79 c8 b6 00 00 00 00 76 7a d1 b7 88 9c c6 b6 c0 f7 c5 b6 00 00 00 00 01 00 00 00 01 00 00 00 d3 4d 62 10 01 00 00 00 00 00 00 10 00]
< Call State (len=68) [ Ext: 0  Coding: CCITT (ITU) standard (0)  Call state: Null (0)
[Jun 25 09:18:18] ERROR[5760]: chan_dahdi.c:10710 dahdi_pri_error: XXX Message longer than it should be?? XXX
-- Processing IE 41 (cs0, Date/Time)
-- Processing IE 76 (cs0, Connected Number)
[Jun 25 09:18:18] ERROR[5760]: chan_dahdi.c:10710 dahdi_pri_error: XXX Message longer than it should be?? XXX
Sending Receiver Ready (9)

> [ 02 01 01 12 ]

> Supervisory frame:
> SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 009 P/F: 0
> 0 bytes of data
-- Restarting T203 timer
T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (9)

> [ 00 01 01 13 ]

> Supervisory frame:
> SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 009 P/F: 1
> 0 bytes of data
OptiCentral*CLI> 
< [ 00 01 01 11 ]

< Supervisory frame:
< SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
< Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 008 P/F: 1
< 0 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 8
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
-- Got RR response to our frame
-- Restarting T203 timer
OptiCentral*CLI> 
< [ 02 01 12 10 08 01 84 45 08 02 80 90 ]

< Informational frame:
< SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
< N(S): 009   0: 0
< N(R): 008   P: 0
< 8 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 8
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8)  len=8
< Call Ref: len= 1 (reference 4/0x4) (Terminator)
< Message type: DISCONNECT (69)
< [08 02 80 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
q931.c:3820 q931_receive: call 32772 on channel 1 enters state 12 (Disconnect Indication)
Sending Receiver Ready (10)

> [ 02 01 01 14 ]

> Supervisory frame:
> SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 010 P/F: 0
> 0 bytes of data
-- Restarting T203 timer
    -- Channel 0/1, span 1 got hangup request, cause 16
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
q931.c:2961 q931_release: call 32772 on channel 1 enters state 19 (Release Request)

> [ 00 01 10 14 08 01 04 4d 08 02 81 90 ]

> Informational frame:
> SAPI: 00  C/R: 0 EA: 0
>  TEI: 000        EA: 1
> N(S): 008   0: 0
> N(R): 010   P: 0
> 8 bytes of data
Stopping T_203 timer
Starting T_200 timer
-- Restarting T200 timer
> Protocol Discriminator: Q.931 (8)  len=8
> Call Ref: len= 1 (reference 4/0x4) (Originator)
> Message type: RELEASE (77)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
>                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
    -- Hungup 'DAHDI/1-1'
    -- No one is available to answer at this time (1:0/0/0)
    -- Executing [0123xxxxx9@trucar-fora:10] Goto("SIP/209-b6e03230", "NOANSWER,1") in new stack
    -- Goto (trucar-fora,NOANSWER,1)
    -- Executing [NOANSWER@trucar-fora:1] Hangup("SIP/209-b6e03230", "") in new stack
  == Spawn extension (trucar-fora, NOANSWER, 1) exited non-zero on 'SIP/209-b6e03230'
    -- Executing [h@trucar-fora:1] Gosub("SIP/209-b6e03230", "hangupcall,h,1") in new stack
    -- Executing [h@hangupcall:1] ResetCDR("SIP/209-b6e03230", "wv") in new stack
    -- Executing [h@hangupcall:2] NoCDR("SIP/209-b6e03230", "") in new stack
OptiCentral*CLI> 
< [ 00 01 01 12 ]

< Supervisory frame:
< SAPI: 00  C/R: 0 EA: 0
<  TEI: 000        EA: 1
< Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
< N(R): 009 P/F: 0
< 0 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 7 to (but not including) 9
-- ACKing packet 8, new txqueue is -1 (-1 means empty)
-- Since there was nothing left, stopping T200 counter
-- Nothing left, starting T203 counter
-- Restarting T203 timer
OptiCentral*CLI> 
< [ 02 01 14 12 08 01 84 5a ]

< Informational frame:
< SAPI: 00  C/R: 1 EA: 0
<  TEI: 000        EA: 1
< N(S): 010   0: 0
< N(R): 009   P: 0
< 4 bytes of data
Handling message for SAPI/TEI=0/0
-- ACKing all packets from 8 to (but not including) 9
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8)  len=4
< Call Ref: len= 1 (reference 4/0x4) (Terminator)
< Message type: RELEASE COMPLETE (90)
q931.c:3760 q931_receive: call 32772 on channel 1 enters state 0 (Null)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
Sending Receiver Ready (11)

> [ 02 01 01 16 ]

> Supervisory frame:
> SAPI: 00  C/R: 1 EA: 0
>  TEI: 000        EA: 1
> Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
> N(R): 011 P/F: 0
> 0 bytes of data
-- Restarting T203 timer