rendered paste body< Protocol Discriminator: Q.931 (8) len=69
< Call Ref: len= 2 (reference 133/0x85) (Originator)
< Message type: SETUP (5)
< [04 03 90 90 a2]
< Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: 3.1kHz audio (16)
< Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
< Ext: 1 User information layer 1: u-Law (34)
< [18 03 a9 83 82]
< 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: 2 ]
< [1c 15 9f 8b 01 00 a1 0f 02 01 01 06 07 2a 86 48 ce 15 00 04 0a 01 00]
< Facility (len=23, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x0f, 0x02, 0x01, 0x01, 0x06, 0x07, 0x2a, 0x86, 'H', 0xce, 0x15, 0x00, 0x04, 0x0a, 0x01, 0x00 ]
< [1e 02 82 83]>
< 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: Calling equipment is non-ISDN. (3) ]
< [6c 0c 21 80 32 31 32 35 35 35 31 32 31 32]
< 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 not screened (0) '2125551212' ]
< [70 0b a1 35 37 34 36 37 34 38 32 39 31]
< Called Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '5746748291' ]
-- Making new call for cr 133
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 28 (cs0, Facility)
Handle Q.932 ROSE Invoke component
Don't know what to do if second ROSE component is of type 0x6
-- Processing IE 30 (cs0, Progress Indicator)
-- Processing IE 108 (cs0, Calling Party Number)
-- Processing IE 112 (cs0, Called Party Number)
> Protocol Discriminator: Q.931 (8) len=10
> Call Ref: len= 2 (reference 133/0x85) (Terminator)
> Message type: CALL PROCEEDING (2)
> [18 03 a9 83 82]
> 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: 2 ]
-- Accepting call from '2125551212' to '5746748291' on channel 0/2, span 1
Mar 2 23:33:29 DEBUG[6070]: chan_zap.c:1405 zt_enable_ec: Enabled echo cancellation on channel 2
-- Executing Answer("Zap/2-1", "") in new stack
Mar 2 23:33:29 DEBUG[6065]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'Zap/2-1'
> Protocol Discriminator: Q.931 (8) len=14
> Call Ref: len= 2 (reference 133/0x85) (Terminator)
> Message type: CONNECT (7)
> [18 03 a9 83 82]
> 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: 2 ]
> [1e 02 81 82]
> Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Progress Description: Called equipment is non-ISDN. (2) ]
-- Executing Monitor("Zap/2-1", "") in new stack
-- Executing BackGround("Zap/2-1", "welcome&silence/10") in new stack
Mar 2 23:33:29 DEBUG[6065]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'Zap/2-1'
Mar 2 23:33:29 DEBUG[6523]: channel.c:1713 ast_settimeout: Scheduling timer at 160 sample intervals
-- Playing 'welcome' (language 'en')
< Protocol Discriminator: Q.931 (8) len=5
< Call Ref: len= 2 (reference 133/0x85) (Originator)
< Message type: CONNECT ACKNOWLEDGE (15)
< Protocol Discriminator: Q.931 (8) len=34
< Call Ref: len= 2 (reference 133/0x85) (Originator)
< Message type: FACILITY (98)
< [1c 1b 9f 8b 01 00 a1 15 02 01 01 02 01 00 80 0d 4e 45 57 20 59 4f 52 4b 20 43 41 4c 4c]
< Facility (len=29, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x15, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x0d, 'NEW', 0x20, 'YORK', 0x20, 'CALL' ]
-- Processing IE 28 (cs0, Facility)
Handle Q.932 ROSE Invoke component
Mar 2 23:33:30 DEBUG[6070]: chan_zap.c:1385 zt_enable_ec: Echo cancellation already on
Mar 2 23:33:30 DEBUG[6523]: channel.c:1713 ast_settimeout: Scheduling timer at 0 sample intervals
Mar 2 23:33:30 DEBUG[6523]: channel.c:1713 ast_settimeout: Scheduling timer at 0 sample intervals
Mar 2 23:33:30 DEBUG[6523]: channel.c:1713 ast_settimeout: Scheduling timer at 160 sample intervals
-- Playing 'silence/10' (language 'en')
osceola-pri*CLI>
== CDR updated on Zap/1-1
-- Executing NoOp("Zap/1-1", "DTMF detection successful") in new stack
-- Executing Hangup("Zap/1-1", "") in new stack
== Spawn extension (inbound-pri, 1234567890, 2) exited non-zero on 'Zap/1-1'
-- Executing Hangup("Zap/1-1", "") in new stack
== Spawn extension (inbound-pri, h, 1) exited non-zero on 'Zap/1-1'
Mar 2 23:33:32 DEBUG[6520]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record.
Mar 2 23:33:32 DEBUG[6520]: cdr_addon_mysql.c:222 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2006-03-02 23:33:11','\"NEW YORK CALL\" <2125551212>','2125551212','1234567890','inbound-pri', 'Zap/1-1','','Hangup','',21,21,'ANSWERED',3,'')
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:2864 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/1-1
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:2345 zt_hangup: Hangup: channel: 1 index = 0, normal = 11, callwait = -1, thirdcall = -1
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:2494 zt_hangup: Not yet hungup... Calling hangup once with icause, and clearing call
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate Active
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 132/0x84) (Terminator)
> 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: Unknown (16), class = Normal Event (1) ]
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:1437 zt_disable_ec: disabled echo cancellation on channel 1
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:2785 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/1-1
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:1374 update_conf: Updated conferencing on 1, with 0 conference users
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:2860 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
Mar 2 23:33:32 DEBUG[6520]: chan_zap.c:1437 zt_disable_ec: disabled echo cancellation on channel 1
-- Hungup 'Zap/1-1'
< Protocol Discriminator: Q.931 (8) len=5
< Call Ref: len= 2 (reference 132/0x84) (Originator)
< Message type: RELEASE (77)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 132/0x84) (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: Unknown (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
Mar 2 23:33:33 DEBUG[6077]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '2083e8582331a7815118ebf14279b5fa@64.74.225.137' of Request 102: Match Found
Mar 2 23:33:34 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 1 on Zap/2-1
Mar 2 23:33:34 DEBUG[6523]: channel.c:1713 ast_settimeout: Scheduling timer at 0 sample intervals
Mar 2 23:33:34 DEBUG[6523]: pbx.c:2288 __ast_pbx_run: Oooh, got something to jump out with ('1')!
Mar 2 23:33:34 DEBUG[6077]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '1ed323c7338e1bee05145f0e5744a14b@64.74.225.137' of Request 102: Match Found
Mar 2 23:33:35 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 2 on Zap/2-1
Mar 2 23:33:36 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 3 on Zap/2-1
Mar 2 23:33:38 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 4 on Zap/2-1
Mar 2 23:33:39 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 5 on Zap/2-1
Mar 2 23:33:42 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 7 on Zap/2-1
Mar 2 23:33:43 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 8 on Zap/2-1
Mar 2 23:33:44 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 9 on Zap/2-1
Mar 2 23:33:46 DEBUG[6523]: chan_zap.c:4628 zt_read: DTMF digit: 0 on Zap/2-1
== CDR updated on Zap/2-1
-- Executing NoOp("Zap/2-1", "DTMF detection seems to have FAILED on 123457890") in new stack
< Protocol Discriminator: Q.931 (8) len=9
< Call Ref: len= 2 (reference 133/0x85) (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: Unknown (16), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
-- Channel 0/2, span 1 got hangup request
-- Executing Hangup("Zap/2-1", "") in new stack
== Spawn extension (inbound-pri, h, 1) exited non-zero on 'Zap/2-1'
Mar 2 23:33:52 DEBUG[6523]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record.
Mar 2 23:33:52 DEBUG[6523]: cdr_addon_mysql.c:222 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2006-03-02 23:33:29','\"NEW YORK CALL\" <2125551212>','2125551212','123457890','inbound-pri', 'Zap/2-1','','Hangup','',23,23,'ANSWERED',3,'')
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:2864 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/2-1
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:2345 zt_hangup: Hangup: channel: 2 index = 0, normal = 12, callwait = -1, thirdcall = -1
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:2494 zt_hangup: Not yet hungup... Calling hangup once with icause, and clearing call
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 133/0x85) (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: Unknown (16), class = Normal Event (1) ]
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:1437 zt_disable_ec: disabled echo cancellation on channel 2
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:2785 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/2-1
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:1374 update_conf: Updated conferencing on 2, with 0 conference users
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:2860 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
Mar 2 23:33:52 DEBUG[6523]: chan_zap.c:1437 zt_disable_ec: disabled echo cancellation on channel 2
-- Hungup 'Zap/2-1'
< Protocol Discriminator: Q.931 (8) len=5
< Call Ref: len= 2 (reference 133/0x85) (Originator)
< Message type: RELEASE COMPLETE (90)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null