Connected to Asterisk SVN-tag-icallrps-tdmgateway-asterisk1.2.9.1-netsec-v1.0.0-r12061 currently running on linux800 (pid = 30006) -- Remote UNIX connection Verbosity is at least 7 Core debug is at least 10 [Span 0 D-Channel 1]< Protocol Discriminator: Q.931 (8) len=68 [Span 0 D-Channel 1]< Call Ref: len= 2 (reference 4496/0x1190) (Originator) [Span 0 D-Channel 1]< Message type: SETUP (5) < [04 03 80 90 a2] [Span 0 D-Channel 1]< Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) [Span 0 D-Channel 1]< Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) [Span 0 D-Channel 1]< Ext: 1 User information layer 1: u-Law (34) < [18 04 e9 80 83 85] [Span 0 D-Channel 1]< Channel ID (len= 6) [ Ext: 1 IntID: Explicit, PRI Spare: 0, Exclusive Dchan: 0 [Span 0 D-Channel 1]< ChanSel: Reserved [Span 0 D-Channel 1]< Ext: 1 DS1 Identifier: 0 [Span 0 D-Channel 1]< Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Span 0 D-Channel 1]< Ext: 1 Channel: 5 ] < [20 02 00 e2] [Span 0 D-Channel 1]< Network-Specific Facilities (len= 2) [ [Span 0 D-Channel 1]Toll Free MEGACOM[Span 0 D-Channel 1] ] < [28 01 92] [Span 0 D-Channel 1]< Display (len= 1) Charset: 12 [ ] < [28 01 95] [Span 0 D-Channel 1]< Display (len= 1) Charset: 15 [ ] < [28 0d b1 4c 41 52 52 59 20 42 55 54 4c 45 52] [Span 0 D-Channel 1]< Display (len=13) Charset: 31 [ LARRY BUTLER ] < [6c 0c 21 83 33 33 34 37 36 38 37 30 35 35] [Span 0 D-Channel 1]< Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) [Span 0 D-Channel 1]< Presentation: Presentation allowed of network provided number (3) '3347687055' ] < [70 0b a1 38 36 36 35 38 36 38 36 34 30] [Span 0 D-Channel 1]< Called Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '8665868640' ] [Span 0 D-Channel 1]-- Making new call for cr 4496 [Span 0 D-Channel 1]-- Processing Q.931 Call Setup [Span 0 D-Channel 1]-- Processing IE 4 (cs0, Bearer Capability) [Span 0 D-Channel 1]-- Processing IE 24 (cs0, Channel Identification) [Span 0 D-Channel 1]-- Processing IE 32 (cs0, Network-Specific Facilities) [Span 0 D-Channel 1]-- Processing IE 40 (cs0, Display) [Span 0 D-Channel 1]-- Processing IE 40 (cs0, Display) [Span 0 D-Channel 1]-- Processing IE 40 (cs0, Display) [Span 0 D-Channel 1]-- Processing IE 108 (cs0, Calling Party Number) [Span 0 D-Channel 1]-- Processing IE 112 (cs0, Called Party Number) -- Going to extension s|1 because of immediate=yes [Span 0 D-Channel 0]> Protocol Discriminator: Q.931 (8) len=11 [Span 0 D-Channel 0]> Call Ref: len= 2 (reference 4496/0x1190) (Terminator) [Span 0 D-Channel 0]> Message type: CALL PROCEEDING (2) > [18 04 e9 80 83 85] [Span 0 D-Channel 0]> Channel ID (len= 6) [ Ext: 1 IntID: Explicit, PRI Spare: 0, Exclusive Dchan: 0 [Span 0 D-Channel 0]> ChanSel: Reserved [Span 0 D-Channel 0]> Ext: 1 DS1 Identifier: 0 [Span 0 D-Channel 0]> Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Span 0 D-Channel 0]> Ext: 1 Channel: 5 ] -- Accepting call from '3347687055' to 's' on channel 0/5, span 1 -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Channel Entered inbound Context - DNIS:[8665868640] - ANI:[3347687055] - Timestamp:[1158281760]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Channel Entered inbound Context - DNIS:[8665868640] - ANI:[3347687055] - Timestamp:[1158281760] -- Executing Set("Zap/5-1", "RP_APP_DIALSTRING_PRI=SIP/sipproxy1/8665868640") in new stack -- Executing Set("Zap/5-1", "RP_APP_DIALSTRING_SEC=SIP/sipproxy2/8665868640") in new stack -- Executing SIPAddHeader("Zap/5-1", "X-icallrps-gw-ani: 3347687055") in new stack -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-ani: 3347687055]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-ani: 3347687055] -- Executing SIPAddHeader("Zap/5-1", "X-icallrps-gw-uid: linux800-1158281760.8") in new stack -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-ucid: linux800-1158281760.8]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-ucid: linux800-1158281760.8] -- Executing SIPAddHeader("Zap/5-1", "X-icallrps-gw-host: linux800") in new stack -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-host: linux800]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-host: linux800] -- Executing SIPAddHeader("Zap/5-1", "X-icallrps-gw-channel: Zap/5-1") in new stack -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-channel: Zap/5-1]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Set SIP_HEADER:[X-icallrps-gw-channel: Zap/5-1] -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Dialing Record/Playback App Server - RP_APP_DIALSTRING:[SIP/sipproxy1/8665868640]") in new stack Sep 14 20:56:00 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Dialing Record/Playback App Server - RP_APP_DIALSTRING:[SIP/sipproxy1/8665868640] -- Executing Dial("Zap/5-1", "SIP/sipproxy1/8665868640|2.5|gn") in new stack -- Called sipproxy1/8665868640 -- SIP/sipproxy1-615d answered Zap/5-1 [Span 0 D-Channel 0]> Protocol Discriminator: Q.931 (8) len=15 [Span 0 D-Channel 0]> Call Ref: len= 2 (reference 4496/0x1190) (Terminator) [Span 0 D-Channel 0]> Message type: CONNECT (7) > [18 04 e9 80 83 85] [Span 0 D-Channel 0]> Channel ID (len= 6) [ Ext: 1 IntID: Explicit, PRI Spare: 0, Exclusive Dchan: 0 [Span 0 D-Channel 0]> ChanSel: Reserved [Span 0 D-Channel 0]> Ext: 1 DS1 Identifier: 0 [Span 0 D-Channel 0]> Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Span 0 D-Channel 0]> Ext: 1 Channel: 5 ] > [1e 02 81 82] [Span 0 D-Channel 0]> Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) [Span 0 D-Channel 0]> Ext: 1 Progress Description: Called equipment is non-ISDN. (2) ] [Span 0 D-Channel 1]< Protocol Discriminator: Q.931 (8) len=5 [Span 0 D-Channel 1]< Call Ref: len= 2 (reference 4496/0x1190) (Originator) [Span 0 D-Channel 1]< Message type: CONNECT ACKNOWLEDGE (15) [Span 0 D-Channel 1]Timed out looking for connect acknowledge [Span 0 D-Channel 1]> Protocol Discriminator: Q.931 (8) len=9 [Span 0 D-Channel 1]> Call Ref: len= 2 (reference 4496/0x1190) (Terminator) [Span 0 D-Channel 1]> Message type: DISCONNECT (69) > [08 02 81 90] [Span 0 D-Channel 1]> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) [Span 0 D-Channel 1]> Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] [Span 0 D-Channel 1]< Protocol Discriminator: Q.931 (8) len=5 [Span 0 D-Channel 1]< Call Ref: len= 2 (reference 4496/0x1190) (Originator) [Span 0 D-Channel 1]< Message type: RELEASE (77) -- Channel 0/5, span 1 got hangup -- Executing ICallLogger("Zap/5-1", "3|UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Session Hungup - Origination:[-1] - Timestamp:[1158281764]") in new stack Sep 14 20:56:04 NOTICE[30917]: app_logger_icall.c:101 icalllogger_exec: UniqueID:[1158281760.8] | Channel:[Zap/5-1] | Context:[inbound] - Session Hungup - Origination:[-1] - Timestamp:[1158281764] [Span 0 D-Channel 0]NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request [Span 0 D-Channel 0]> Protocol Discriminator: Q.931 (8) len=9 [Span 0 D-Channel 0]> Call Ref: len= 2 (reference 4496/0x1190) (Terminator) [Span 0 D-Channel 0]> Message type: RELEASE COMPLETE (90) > [08 02 81 90] [Span 0 D-Channel 0]> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) [Span 0 D-Channel 0]> Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] [Span 0 D-Channel 0]NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null [Span 0 D-Channel 0]NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null