thr3ads.net - asterisk users - [asterisk-users] IAX softphone fails through PRI trunks with Hangup [Jan 2007]

If this information is useful, please help other people find it:
Share via:

Patrick W. Foster

2007-Jan-25 09:49 UTC

[asterisk-users] IAX softphone fails through PRI trunks with Hangup

I've a call center using IAX softphones provided by a third party.  
We've observed problems where the IAX phones seem unable to use our PRI 
trunks.  A sample anonymized call is provided below with the PRI debug 
calls embedded.  Any thoughts,
comments or suggestions would be welcome.  In anonymizing it, I preseved 
the format
and number of digits sent.

    -- Accepting AUTHENTICATED call from 192.168.1.164:
       > requested format = alaw,
       > requested prefs = (),
       > actual format = ulaw,
       > host prefs = (ulaw|alaw|gsm),
       > priority = mine
    -- Executing Set("IAX2/4427-1", "EMERGENCYROUTE=YES") in
new stack
    -- Executing Macro("IAX2/4427-1",
"dialout-trunk|1|6167XXXXX||") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "1?3:2") in new stack
    -- Goto (macro-dialout-trunk,s,3)
    -- Executing Macro("IAX2/4427-1", "user-callerid") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?start") in new
stack
    -- Executing Set("IAX2/4427-1", "REALCALLERIDNUM=4427")
in new stack
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1", "AMPUSER=4427") in new
stack
    -- Executing Set("IAX2/4427-1",
"AMPUSERCIDNAME=USER18-IAX") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)=USER18-IAX
<4427>")
in new stack
    -- Executing NoOp("IAX2/4427-1", "Using CallerID
"USER18-IAX"
<4427>") in new stack
    -- Executing Macro("IAX2/4427-1",
"record-enable|4427|OUT") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0 > 0?2:4") in
new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing AGI("IAX2/4427-1", 
"recordingcheck|20070125-102531|1169738731.2435") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20070125-102531|1169738731.2435: Outbound recording not 
enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("IAX2/4427-1", "No recording needed")
in new stack
    -- Executing Macro("IAX2/4427-1", "outbound-callerid|1")
in new stack
    -- Executing GotoIf("IAX2/4427-1", "1?start") in new
stack
    -- Goto (macro-outbound-callerid,s,3)
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1",
"USEROUTCID=<8xx-6xx-xxxx>") in new
stack
    -- Executing Set("IAX2/4427-1", "EMERGENCYCID=") in new
stack
    -- Executing Set("IAX2/4427-1", "TRUNKOUTCID="Business
Name"
<5xx-6xx-xxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?trunkcid") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "1?trunkcid") in new
stack
    -- Goto (macro-outbound-callerid,s,11)
    -- Executing GotoIf("IAX2/4427-1", "0?usercid") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)="Business
Name"
<5xx-6xx-xxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1",
"CALLERID(all)=<8xx-6xx-xxxx>") in
new stack
    -- Executing NoOp("IAX2/4427-1", "CallerID set to
"" <8xx6xxxxxx>")
in new stack
    -- Executing Set("IAX2/4427-1", "GROUP()=OUT_1") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?108") in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_NUMBER=6167XXXXX")
in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_TRUNK=1") in new
stack
    -- Executing AGI("IAX2/4427-1", "fixlocalprefix") in new
stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
    -- AGI Script fixlocalprefix completed, returning 0
    -- Executing Set("IAX2/4427-1", "OUTNUM=6167XXXXX") in
new stack
    -- Executing Set("IAX2/4427-1", "custom=ZAP/g1") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?16") in new stack
    -- Executing Dial("IAX2/4427-1",
"ZAP/g1/6167XXXXX|150|r") in new stack
-- Making new call for cr 33745
    -- Requested transfer capability: 0x00 - SPEECH
 > Protocol Discriminator: Q.931 (8)  len=46
 > Call Ref: len= 2 (reference 977/0x3D1) (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 84]
 > 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: 4 ]
 > [1e 02 80 83]
 > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard 
(0) 0: 0   Location: User (0)
 >                               Ext: 1  Progress Description: Calling 
equipment is non-ISDN. (3) ]
 > [6c 0c 21 81 38 30 30 36 39 35 39 38 39 37]
 > Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)
 >                           Presentation: Presentation permitted, user 
number passed network screening (1) '8xx6xxxxxx' ]
 > [70 0b 80 36 31 36 37 38 34 32 37 36 37]
 > Called Number (len=13) [ Ext: 1  TON: Unknown Number Type (0)  NPI: 
Unknown Number Plan (0) '6167XXXXX' ]
    -- Called g1/6167XXXXX
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 977/0x3D1) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 84]
< 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: 4 ]
-- Processing IE 24 (cs0, Channel Identification)
    -- Zap/4-1 is proceeding passing it to IAX2/4427-1
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 977/0x3D1) (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)
    -- Zap/4-1 is making progress passing it to IAX2/4427-1
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate 
Connect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 974/0x3CE) (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/1-1'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5426-b7405370' in macro 'dialout-trunk'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5426-b7405370'
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 974/0x3CE) (Terminator)
< Message type: RELEASE (77)
< [08 02 82 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the local user (2)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release 
Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 974/0x3CE) (Originator)
 > Message type: RELEASE COMPLETE (90)
 > [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) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate 
Connect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 970/0x3CA) (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/2-1'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5444-b740a8b0' in macro 'dialout-trunk'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5444-b740a8b0'
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 970/0x3CA) (Terminator)
< Message type: RELEASE (77)
< [08 02 82 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the local user (2)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release 
Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 970/0x3CA) (Originator)
 > Message type: RELEASE COMPLETE (90)
 > [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) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 977/0x3D1) (Terminator)
< Message type: DISCONNECT (69)
< [08 02 84 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the remote user (4)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
    -- Channel 0/4, span 1 got hangup request
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, 
peerstate Disconnect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 977/0x3D1) (Originator)
 > 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/4-1'
  == No one is available to answer at this time (1:0/0/0)
    -- Executing Goto("IAX2/4427-1", "s-NOANSWER|1") in new
stack
    -- Goto (macro-dialout-trunk,s-NOANSWER,1)
    -- Executing NoOp("IAX2/4427-1", "Dial failed due to
NOANSWER") in
new stack
    -- Executing Macro("IAX2/4427-1",
"dialout-trunk|3|6167XXXXX||") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "1?3:2") in new stack
    -- Goto (macro-dialout-trunk,s,3)
    -- Executing Macro("IAX2/4427-1", "user-callerid") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "1?start") in new
stack
    -- Goto (macro-user-callerid,s,4)
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1", "AMPUSER=4427") in new
stack
    -- Executing Set("IAX2/4427-1",
"AMPUSERCIDNAME=USER18-IAX") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)=USER18-IAX
<4427>")
in new stack
    -- Executing NoOp("IAX2/4427-1", "Using CallerID
"USER18-IAX"
<4427>") in new stack
    -- Executing Macro("IAX2/4427-1",
"record-enable|4427|OUT") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0 > 0?2:4") in
new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing AGI("IAX2/4427-1", 
"recordingcheck|20070125-102538|1169738731.2435") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 977/0x3D1) (Terminator)
< Message type: RELEASE COMPLETE (90)
< [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) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
  recordingcheck|20070125-102538|1169738731.2435: Outbound recording not 
enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("IAX2/4427-1", "No recording needed")
in new stack
    -- Executing Macro("IAX2/4427-1", "outbound-callerid|3")
in new stack
    -- Executing GotoIf("IAX2/4427-1", "1?start") in new
stack
    -- Goto (macro-outbound-callerid,s,3)
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1",
"USEROUTCID=<8xx-6xx-xxxx>") in new
stack
    -- Executing Set("IAX2/4427-1", "EMERGENCYCID=") in new
stack
    -- Executing Set("IAX2/4427-1", "TRUNKOUTCID="Business
Name"
<5xx-6xx-xxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?trunkcid") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "1?trunkcid") in new
stack
    -- Goto (macro-outbound-callerid,s,11)
    -- Executing GotoIf("IAX2/4427-1", "0?usercid") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)="Business
Name"
<5xx-6xx-xxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1",
"CALLERID(all)=<8xx-6xx-xxxx>") in
new stack
    -- Executing NoOp("IAX2/4427-1", "CallerID set to
"" <8xx6xxxxxx>")
in new stack
    -- Executing Set("IAX2/4427-1", "GROUP()=OUT_3") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?108") in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_NUMBER=6167XXXXX")
in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_TRUNK=3") in new
stack
    -- Executing AGI("IAX2/4427-1", "fixlocalprefix") in new
stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
    -- AGI Script fixlocalprefix completed, returning 0
    -- Executing Set("IAX2/4427-1", "OUTNUM=6167XXXXX") in
new stack
    -- Executing Set("IAX2/4427-1", "custom=ZAP/g2") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?16") in new stack
    -- Executing Dial("IAX2/4427-1",
"ZAP/g2/6167XXXXX|150|r") in new stack
-- Making new call for cr 32922
    -- Requested transfer capability: 0x00 - SPEECH
 > Protocol Discriminator: Q.931 (8)  len=46
 > Call Ref: len= 2 (reference 154/0x9A) (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 81]
 > 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: 1 ]
 > [1e 02 80 83]
 > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard 
(0) 0: 0   Location: User (0)
 >                               Ext: 1  Progress Description: Calling 
equipment is non-ISDN. (3) ]
 > [6c 0c 21 81 38 30 30 36 39 35 39 38 39 37]
 > Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)
 >                           Presentation: Presentation permitted, user 
number passed network screening (1) '8xx6xxxxxx' ]
 > [70 0b 80 36 31 36 37 38 34 32 37 36 37]
 > Called Number (len=13) [ Ext: 1  TON: Unknown Number Type (0)  NPI: 
Unknown Number Plan (0) '6167XXXXX' ]
    -- Called g2/6167XXXXX
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 154/0x9A) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 81]
< 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: 1 ]
-- Processing IE 24 (cs0, Channel Identification)
    -- Zap/25-1 is proceeding passing it to IAX2/4427-1
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 154/0x9A) (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)
    -- Zap/25-1 is making progress passing it to IAX2/4427-1
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate 
Connect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 973/0x3CD) (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/6-1'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5435-b741a570' in macro 'dialout-trunk'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5435-b741a570'
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 973/0x3CD) (Terminator)
< Message type: RELEASE (77)
< [08 02 82 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the local user (2)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release 
Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 973/0x3CD) (Originator)
 > Message type: RELEASE COMPLETE (90)
 > [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) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 154/0x9A) (Terminator)
< Message type: DISCONNECT (69)
< [08 02 84 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the remote user (4)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
    -- Channel 0/1, span 2 got hangup request
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, 
peerstate Disconnect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 154/0x9A) (Originator)
 > 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/25-1'
  == No one is available to answer at this time (1:0/0/0)
    -- Executing Goto("IAX2/4427-1", "s-NOANSWER|1") in new
stack
    -- Goto (macro-dialout-trunk,s-NOANSWER,1)
    -- Executing NoOp("IAX2/4427-1", "Dial failed due to
NOANSWER") in
new stack
    -- Executing Macro("IAX2/4427-1",
"dialout-trunk|2|6167XXXXX||") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "1?3:2") in new stack
    -- Goto (macro-dialout-trunk,s,3)
    -- Executing Macro("IAX2/4427-1", "user-callerid") in
new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "1?start") in new
stack
    -- Goto (macro-user-callerid,s,4)
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1", "AMPUSER=4427") in new
stack
    -- Executing Set("IAX2/4427-1",
"AMPUSERCIDNAME=USER18-IAX") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)=USER18-IAX
<4427>")
in new stack
    -- Executing NoOp("IAX2/4427-1", "Using CallerID
"USER18-IAX"
<4427>") in new stack
    -- Executing Macro("IAX2/4427-1",
"record-enable|4427|OUT") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0 > 0?2:4") in
new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing AGI("IAX2/4427-1", 
"recordingcheck|20070125-102545|1169738731.2435") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 154/0x9A) (Terminator)
< Message type: RELEASE COMPLETE (90)
< [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) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
  recordingcheck|20070125-102545|1169738731.2435: Outbound recording not 
enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("IAX2/4427-1", "No recording needed")
in new stack
    -- Executing Macro("IAX2/4427-1", "outbound-callerid|2")
in new stack
    -- Executing GotoIf("IAX2/4427-1", "1?start") in new
stack
    -- Goto (macro-outbound-callerid,s,3)
    -- Executing NoOp("IAX2/4427-1", "REALCALLERIDNUM is
4427") in new stack
    -- Executing Set("IAX2/4427-1",
"USEROUTCID=<8xx-6xx-xxxx>") in new
stack
    -- Executing Set("IAX2/4427-1", "EMERGENCYCID=") in new
stack
    -- Executing Set("IAX2/4427-1", "TRUNKOUTCID="Business
Name"
<5xx6xxxxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?trunkcid") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "1?trunkcid") in new
stack
    -- Goto (macro-outbound-callerid,s,11)
    -- Executing GotoIf("IAX2/4427-1", "0?usercid") in new
stack
    -- Executing Set("IAX2/4427-1", "CALLERID(all)="Business
Name"
<5xx6xxxxxx>") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?report") in new
stack
    -- Executing Set("IAX2/4427-1",
"CALLERID(all)=<8xx-6xx-xxxx>") in
new stack
    -- Executing NoOp("IAX2/4427-1", "CallerID set to
"" <8006959897>")
in new stack
    -- Executing Set("IAX2/4427-1", "GROUP()=OUT_2") in new
stack
    -- Executing GotoIf("IAX2/4427-1", "0?108") in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_NUMBER=6167XXXXX")
in new stack
    -- Executing Set("IAX2/4427-1", "DIAL_TRUNK=2") in new
stack
    -- Executing AGI("IAX2/4427-1", "fixlocalprefix") in new
stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
    -- AGI Script fixlocalprefix completed, returning 0
    -- Executing Set("IAX2/4427-1", "OUTNUM=6167XXXXX") in
new stack
    -- Executing Set("IAX2/4427-1",
"custom=SIP/SIPOUTBOUND") in new stack
    -- Executing GotoIf("IAX2/4427-1", "0?16") in new stack
    -- Executing Dial("IAX2/4427-1",
"SIP/SIPOUTBOUND/6167XXXXX|150|r")
in new stack
    -- Called SIPOUTBOUND/6167XXXXX
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate 
Connect Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 975/0x3CF) (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/3-1'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5421-b7420120' in macro 'dialout-trunk'
  == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 
'SIP/5421-b7420120'
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 975/0x3CF) (Terminator)
< Message type: RELEASE (77)
< [08 02 82 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   
Location: Public network serving the local user (2)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
-- Processing IE 8 (cs0, Cause)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release 
Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 975/0x3CF) (Originator)
 > Message type: RELEASE COMPLETE (90)
 > [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) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
    -- SIP/SIPOUTBOUND-08686f90 is making progress passing it to IAX2/4427-1
    -- SIP/SIPOUTBOUND-08686f90 answered IAX2/4427-1

Tim Panton

2007-Jan-25 15:47 UTC

head link

[asterisk-users] IAX softphone fails through PRI trunks with Hangup

On 25 Jan 2007, at 16:48, Patrick W. Foster wrote:
> I've a call center using IAX softphones provided by a third party.   
> We've observed problems where the IAX phones seem unable to use our  
> PRI trunks.  A sample anonymized call is provided below with the  
> PRI debug calls embedded.  Any thoughts,
> comments or suggestions would be welcome.  In anonymizing it, I  
> preseved the format
> and number of digits sent.
Do you have an IAX trace (either etherreal or IAX2 debug ) of a  
failed call ?

We had a similar problem in an early version of our  IAX softphone.
When I put the state-machine together I didn't expect the ringing  
message _ever_
come after a call is answered.

But it can, if you have

exten => s,1,Answer()
exten => s,2,Playback(your-call-may-be-recorded-blah-blah)
exten => s,3,Dial(Zap/g1/004416128824245) ; this line can generate a  
ringing message....

Tim.

Tim Panton

www.mexuar.net
www.westhawk.co.uk/

Possibly Parallel Threads

Search for more reasonably related threads

asterisk users - Jan 2007 - IAX softphone fails through PRI trunks with Hangup

[asterisk-users] IAX softphone fails through PRI trunks with Hangup

[asterisk-users] IAX softphone fails through PRI trunks with Hangup

Possibly Parallel Threads