ip office to bt pri Connected to Asterisk 1.4.13 currently running on localhost (pid = 2899) Verbosity is at least 9 Core debug is at least 9 Really destroying SIP dialog '6e61faef1fb7c75f016bacc4491e7ed6@194.50.55.12' Method: BYE Really destroying SIP dialog '1391735014@127.0.0.1' Method: OPTIONS < Protocol Discriminator: Q.931 (8) len=30 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: SETUP (5) < [04 03 80 90 a3] < 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: A-Law (35) < [6c 0b a1 38 37 30 37 35 31 34 30 34 30] < Calling Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) < Presentation: Presentation permitted, user number not screened (0) '8707514040' ] < [70 05 80 30 37 39 37] < Called Number (len= 7) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '0797' ] -- Making new call for cr 79 -- Processing Q.931 Call Setup -- Processing IE 4 (cs0, Bearer Capability) -- Processing IE 108 (cs0, Calling Party Number) -- Processing IE 112 (cs0, Called Party Number) q931.c:3296 q931_receive: call 79 on channel -1 enters state 6 (Call Present) q931.c:2622 q931_setup_ack: call 79 on channel 31 enters state 25 (Overlap Receiving) > Protocol Discriminator: Q.931 (8) len=14 > Call Ref: len= 2 (reference 79/0x4F) (Terminator) > Message type: SETUP ACKNOWLEDGE (13) > [18 03 a9 83 9f] > 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: 31 ] > [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) ] -- Starting simple switch on 'Zap/62-1' -- Accepting overlap call from '8707514040' to '0797' on channel 0/31, span 2 < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 33] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '3' ] -- Processing IE 112 (cs0, Called Party Number) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 32] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '2' ] -- Processing IE 112 (cs0, Called Party Number) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 32] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '2' ] -- Processing IE 112 (cs0, Called Party Number) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 32] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '2' ] -- Processing IE 112 (cs0, Called Party Number) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 32] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '2' ] -- Processing IE 112 (cs0, Called Party Number) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 32] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '2' ] -- Processing IE 112 (cs0, Called Party Number) Really destroying SIP dialog 'f94e1373-bba84682-8e611@10.0.0.162' Method: OPTIONS Really destroying SIP dialog 'f94e1373-cba84682-8e611@10.0.0.162' Method: OPTIONS Really destroying SIP dialog 'f94e1373-dba84682-8e611@10.0.0.162' Method: OPTIONS Really destroying SIP dialog 'f94e1373-eba84682-8e611@10.0.0.162' Method: OPTIONS Really destroying SIP dialog 'f94e1373-fba84682-8e611@10.0.0.162' Method: OPTIONS Really destroying SIP dialog 'f94e1373-0ca84682-8e611@10.0.0.162' Method: OPTIONS < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: INFORMATION (123) < [70 02 80 38] < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '8' ] -- Processing IE 112 (cs0, Called Party Number) -- Executing [07973222228@from-customer-2:1] Set("Zap/62-1", "CALLERID(number)=08707514040") in new stack -- Executing [07973222228@from-customer-2:2] Set("Zap/62-1", "CALLERID(name)=08707514040") in new stack -- Executing [07973222228@from-customer-2:3] AGI("Zap/62-1", "agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4") in new stack AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4 AGI Tx >> agi_request: agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4 AGI Tx >> agi_channel: Zap/62-1 AGI Tx >> agi_language: en AGI Tx >> agi_type: Zap AGI Tx >> agi_uniqueid: 1204017624.40 AGI Tx >> agi_callerid: 08707514040 AGI Tx >> agi_calleridname: 08707514040 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 33 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 0797 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: from-customer-2 AGI Tx >> agi_extension: 07973222228 AGI Tx >> agi_priority: 3 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: AGI Tx >> AGI Rx << SET VARIABLE LANGUAGE() AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ENSWITCH-CALLID AGI Tx >> 200 result=0 AGI Rx << SET VARIABLE __ENSWITCH-CALLID "1204017624.40" AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ENSWITCH-CUTOFF AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE ENSWITCH-SCREEN AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE ENSWITCH-DIVERTED AGI Tx >> 200 result=0 AGI Rx << SET VARIABLE TIMEOUT(absolute) 86400 -- Channel will hangup at 2008-02-27 09:20:28 UTC. AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ENSWITCH-PRESENTATION AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE CALLINGPRES AGI Tx >> 200 result=1 (0) AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-PHONE AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-CUSTOMER AGI Tx >> 200 result=0 AGI Rx << VERBOSE "Resolving type 'external', number '07973222228'." 5 agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4: Resolving type 'external', number '07973222228'. AGI Tx >> 200 result=1 AGI Rx << VERBOSE "select * from customers where id='2' and deleted=0 limit 1" agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4: select * from customers where id='2' and deleted=0 limit 1 AGI Tx >> 200 result=1 AGI Rx << VERBOSE "Customer 2 has 86400 outbound seconds available." 9 agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4: Customer 2 has 86400 outbound seconds available. AGI Tx >> 200 result=1 AGI Rx << VERBOSE "Writing entry to active table for 1204017624.40, :008707514040 - external:07973222228." 9 agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4: Writing entry to active table for 1204017624.40, :008707514040 - external:07973222228. AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE TIMEOUT(absolute) 86400 -- Channel will hangup at 2008-02-27 09:20:29 UTC. AGI Tx >> 200 result=1 AGI Rx << EXEC Monitor gsm|/var/lib/enswitch/recordings/localhost/017/629/record_120401762440_6296 -- AGI Script Executing Application: (Monitor) Options: (gsm|/var/lib/enswitch/recordings/localhost/017/629/record_120401762440_6296) AGI Tx >> 200 result=0 AGI Rx << SET VARIABLE __ENSWITCH-MUSIC default AGI Tx >> 200 result=1 AGI Rx << EXEC SetMusicOnHold default -- AGI Script Executing Application: (SetMusicOnHold) Options: (default) AGI Tx >> 200 result=0 AGI Rx << SET VARIABLE __ENSWITCH-DCUSTOMER "2" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-DTYPE "external" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-DNUMBER "07973222228" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLER "no" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLEE "yes" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE CALLERID(number) "08707514040" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE ENSWITCH-PRESENTATION "0" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-SCUSTOMER "2" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-CUSTOMER "2" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-UNIQUEID "1204017624.40" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-CUTOFF "86400" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-DIAL-TIME "" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE __ENSWITCH-DIAL-OPTIONS "toL(86400000:60000)" AGI Tx >> 200 result=1 AGI Rx << EXEC SetCallerPres allowed_not_screened -- AGI Script Executing Application: (SetCallerPres) Options: (allowed_not_screened) AGI Tx >> 200 result=0 AGI Rx << EXEC Dial Local/07973222228@nta/n|600|o -- AGI Script Executing Application: (Dial) Options: (Local/07973222228@nta/n|600|o) -- Executing [07973222228@nta:1] Set("Local/07973222228@nta-7dce,2", "CALLERID(name)=08707514040") in new stack -- Executing [07973222228@nta:2] Dial("Local/07973222228@nta-7dce,2", "SIP/07973222228@nta||toL(86400000:60000)") in new stack -- Limit Data for this call: > timelimit = 86400000 > play_warning = 60000 > play_to_caller = no > play_to_callee = yes > warning_freq = 0 > start_sound = (null) > warning_sound = timeleft > end_sound = (null) -- Called 07973222228@nta -- Called 07973222228@nta/n -- SIP/nta-09e1a498 is making progress passing it to Local/07973222228@nta-7dce,2 -- Local/07973222228@nta-7dce,1 is making progress passing it to Zap/62-1 > Protocol Discriminator: Q.931 (8) len=9 > Call Ref: len= 2 (reference 79/0x4F) (Terminator) > Message type: PROGRESS (3) > [1e 02 81 88] > 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: Inband information or appropriate pattern now available. (8) ] Really destroying SIP dialog '462642476@127.0.0.1' Method: OPTIONS -- SIP/nta-09e1a498 answered Local/07973222228@nta-7dce,2 -- Local/07973222228@nta-7dce,1 answered Zap/62-1 q931.c:2697 q931_connect: call 79 on channel 31 enters state 10 (Active) > Protocol Discriminator: Q.931 (8) len=14 > Call Ref: len= 2 (reference 79/0x4F) (Terminator) > Message type: CONNECT (7) > [18 03 a9 83 9f] > 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: 31 ] > [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) ] == Spawn extension (nta, 07973222228, 2) exited non-zero on 'Local/07973222228@nta-7dce,2' -- Executing [h@nta:1] DeadAGI("Local/07973222228@nta-7dce,2", "agi://127.0.0.1/end") in new stack AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: end AGI Tx >> agi_request: agi://127.0.0.1/end AGI Tx >> agi_channel: Local/07973222228@nta-7dce,2 AGI Tx >> agi_language: en AGI Tx >> agi_type: Local AGI Tx >> agi_uniqueid: 1204017629.42 AGI Tx >> agi_callerid: 08707514040 AGI Tx >> agi_calleridname: 08707514040 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 0 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: unknown AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: nta AGI Tx >> agi_extension: h AGI Tx >> agi_priority: 1 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: AGI Tx >> AGI Rx << SET VARIABLE LANGUAGE() AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ENSWITCH-CALLID AGI Tx >> 200 result=1 (1204017624.40) AGI Rx << HANGUP AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ANSWEREDTIME AGI Tx >> 200 result=1 (2) AGI Rx << GET VARIABLE ENSWITCH-FAX-FILE AGI Tx >> 200 result=0 -- AGI Script agi://127.0.0.1/end completed, returning 0 AGI Tx >> 200 result=-1 AGI Rx << GET VARIABLE DIALSTATUS AGI Tx >> 200 result=1 (ANSWER) -- AGI Script agi://127.0.0.1/start?ctype=external&cnumber=07973222228&scustomer=2&snumber=008707514040&record_out=4 completed, returning 0 == Auto fallthrough, channel 'Zap/62-1' status is 'ANSWER' -- Executing [h@from-customer-2:1] DeadAGI("Zap/62-1", "agi://127.0.0.1/end") in new stack AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: end AGI Tx >> agi_request: agi://127.0.0.1/end AGI Tx >> agi_channel: Zap/62-1 AGI Tx >> agi_language: en AGI Tx >> agi_type: Zap AGI Tx >> agi_uniqueid: 1204017624.40 AGI Tx >> agi_callerid: 08707514040 AGI Tx >> agi_calleridname: 08707514040 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 33 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 0797 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: from-customer-2 AGI Tx >> agi_extension: h AGI Tx >> agi_priority: 1 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: AGI Tx >> AGI Rx << SET VARIABLE LANGUAGE() AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ENSWITCH-CALLID AGI Tx >> 200 result=1 (1204017624.40) AGI Rx << HANGUP AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE ANSWEREDTIME AGI Tx >> 200 result=1 (2) AGI Rx << GET VARIABLE ENSWITCH-FAX-FILE AGI Tx >> 200 result=0 -- AGI Script agi://127.0.0.1/end completed, returning 0 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate Active q931.c:2763 q931_disconnect: call 79 on channel 31 enters state 11 (Disconnect Request) > Protocol Discriminator: Q.931 (8) len=9 > Call Ref: len= 2 (reference 79/0x4F) (Terminator) > Message type: DISCONNECT (69) > [08 02 81 90] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] -- Hungup 'Zap/62-1' < Protocol Discriminator: Q.931 (8) len=5 < Call Ref: len= 2 (reference 79/0x4F) (Originator) < Message type: RELEASE (77) q931.c:3536 q931_receive: call 79 on channel 31 enters state 0 (Null) NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request > Protocol Discriminator: Q.931 (8) len=9 > Call Ref: len= 2 (reference 79/0x4F) (Terminator) > Message type: RELEASE COMPLETE (90) > [08 02 81 90] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 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 Really destroying SIP dialog '3da0fb062241031530bc4933289a0f89@194.50.55.12' Method: BYE localhost*CLI>