< 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