asterisk*CLI> set verbose 5
Verbosity is at least 5
asterisk*CLI> pri debug span 1
Enabled debugging on span 1
asterisk*CLI> pri debug span 2
Enabled debugging on span 2
< Protocol Discriminator: Q.931 (8)  len=19
< Call Ref: len= 2 (reference 13/0xD) (Originator)
< Message type: SETUP (5)
< [04 03 80 90 a2]
< Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
<                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
<                              Ext: 1  User information layer 1: u-Law (34)
< [18 03 a1 83 96]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0
<                        ChanSel: Reserved
<                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
<                       Ext: 1  Channel: 22 ]
< [70 02 80 38]
< Called Number (len= 4) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0) '8' ]
-- Making new call for cr 13
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 112 (cs0, Called Party Number)
> Protocol Discriminator: Q.931 (8)  len=10
> Call Ref: len= 2 (reference 32781/0x800D) (Terminator)
> Message type: CALL PROCEEDING (2)
> [18 03 a9 83 96]
> Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
>                        ChanSel: Reserved
>                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
>                       Ext: 1  Channel: 22 ]
    -- Executing Answer("Zap/46-1", "") in new stack
    -- Accepting call from '' to '8' on channel 0/22, span 2
> Protocol Discriminator: Q.931 (8)  len=10
> Call Ref: len= 2 (reference 32781/0x800D) (Terminator)
> Message type: CONNECT (7)
> [18 03 a9 83 96]
> Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
>                        ChanSel: Reserved
>                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
>                       Ext: 1  Channel: 22 ]
    -- Executing CallingPres("Zap/46-1", "0") in new stack
    -- Executing SetCallerID("Zap/46-1", "6132718853") in new stack
    -- Executing BackGround("Zap/46-1", "demo-congrats") in new stack
    -- Playing 'demo-congrats' (language 'en')
< Protocol Discriminator: Q.931 (8)  len=5
< Call Ref: len= 2 (reference 13/0xD) (Originator)
< Message type: CONNECT ACKNOWLEDGE (15)
  == CDR updated on Zap/46-1
    -- Executing Dial("Zap/46-1", "Zap/G1/2550048") in new stack
-- Making new call for cr 32969
> Protocol Discriminator: Q.931 (8)  len=39
> Call Ref: len= 2 (reference 201/0xC9) (Originator)
> Message type: SETUP (5)
> [04 03 80 90 a2]
> Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
>                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
>                              Ext: 1  User information layer 1: u-Law (34)
> [18 03 a9 83 96]
> Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
>                        ChanSel: Reserved
>                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
>                       Ext: 1  Channel: 22 ]
> [6c 0c 21 83 36 31 33 32 37 31 38 38 35 33]
> Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
>                           Presentation: Presentation allowed of network provided number (3) '6132718853' ]
> [70 08 a1 32 35 35 30 30 34 38]
> Called Number (len=10) [ Ext: 1  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '2550048' ]
    -- Called G1/2550048
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 32969/0x80C9) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 96]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
<                        ChanSel: Reserved
<                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
<                       Ext: 1  Channel: 22 ]
-- Processing IE 24 (cs0, Channel Identification)
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 32969/0x80C9) (Terminator)
< Message type: PROGRESS (3)
< [1e 02 82 88]
< Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Public network serving the local user (2)
<                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
-- Processing IE 30 (cs0, Progress Indicator)
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 13/0xD) (Originator)
< Message type: DISCONNECT (69)
< [08 02 80 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: User (0)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
    -- Channel 0/22, span 2 got hangup
Jul  6 14:45:41 WARNING[9483]: app_dial.c:362 wait_for_answer: Unable to forward frame
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Outgoing call  Proceeding, peerstate Incoming Call Proceeding
> Protocol Discriminator: Q.931 (8)  len=9
> Call Ref: len= 2 (reference 201/0xC9) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Private network serving the local user (1)
>                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
    -- Hungup 'Zap/22-1'
  == Spawn extension (pbx-pri, 2550048, 1) exited non-zero on 'Zap/46-1'
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
> Protocol Discriminator: Q.931 (8)  len=9
> Call Ref: len= 2 (reference 32781/0x800D) (Terminator)
> Message type: RELEASE (77)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Private network serving the local user (1)
>                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
    -- Hungup 'Zap/46-1'