2005-09-26 19:47:54 VERBOSE[32183] logger.c: -- Accepting AUTHENTICATED call from 82.89.96.19: > requested format = gsm, > requested prefs = (), > actual format = ilbc, > host prefs = (ilbc|gsm|h263p|h263), > priority = mine 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Executing NoOp("IAX2/uptime-16384", "") in new stack 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Executing SetCallerPres("IAX2/uptime-16384", "prohib") in new stack ========================================================== Here I dial, from the iax user "uptime", the number "191". This number if free and is the number of the customer care for business customer of "Telecom Italia",the main telecom company in Italy ========================================================== 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Executing Dial("IAX2/uptime-16384", "Zap/G2/191||S(7200)") in new stack 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Setting call duration limit to 7200 seconds. 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Making new call for cr 32770 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Requested transfer capability: 0x00 - SPEECH 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Protocol Discriminator: Q.931 (8) len=37 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Message type: SETUP (5) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [042005-09-26 19:47:54 VERBOSE[32193] logger.c: > [04 032005-09 -26 19:47:54 VERBOSE[32193] logger.c: > [04 03 802005-09-26 19:47:54 VERBOSE[32193] logger.c: > [04 03 80 902005-09-26 19:47:54 VERBOSE[32193] logger.c: > [04 03 80 90 a32005-09-26 19:47:54 VERBOSE[32193] logger.c: > [04 03 80 90 a3] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Ext: 1 User information layer 1: A-Law (35) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [182005-09-26 19:47:54 VERBOSE[32193] logger.c: > [18 032005-09 -26 19:47:54 VERBOSE[32193] logger.c: > [18 03 a92005-09-26 19:47:54 VERBOSE[32193] logger.c: > [18 03 a9 832005-09-26 19:47:54 VERBOSE[32193] logger.c: > [18 03 a9 83 9f2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [18 03 a9 83 9f] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > ChanSel: Reserved 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Ext: 1 Channel: 31 ] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [282005-09-26 19:47:54 VERBOSE[32193] logger.c: > [28 042005-09 -26 19:47:54 VERBOSE[32193] logger.c: > [28 04 452005-09-26 19:47:54 VERBOSE[32193] logger.c: > [28 04 45 7a2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [28 04 45 7a 692005-09-26 19:47:54 VERBOSE[32193] logger.c: > [28 04 45 7a 69 6f2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [28 04 45 7a 69 6f] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Display (len= 4) ØöÊ ðËB[ Ezio ] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 082005-09 -26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 002005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a32005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 392005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 39 322005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 39 32 322005-09-26 19 :47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 39 32 32 322005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 39 32 32 32 302005-09-26 19:47:54 VERBOS E[32193] logger.c: > [6c 08 00 a3 39 32 32 32 30 342005-09-26 19:47:54 VERBOSE[32193] logger.c: > [6c 08 00 a3 39 32 32 32 30 34] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Calling Number (len=10) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Presentation: Presentation prohibited of network provided number (35) '922204' ] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [2005-09-26 19:47:54 VERBOSE[32193] logger.c: > [702005-09-26 19:47:54 VERBOSE[32193] logger.c: > [70 042005-09 -26 19:47:54 VERBOSE[32193] logger.c: > [70 04 802005-09-26 19:47:54 VERBOSE[32193] logger.c: > [70 04 80 312005-09-26 19:47:54 VERBOSE[32193] logger.c: > [70 04 80 31 392005-09-26 19:47:54 VERBOSE[32193] logger.c: > [70 04 80 31 39 312005-09-26 19:47:54 VERBOSE[32193] logger.c: > [70 04 80 31 39 31] 2005-09-26 19:47:54 VERBOSE[32193] logger.c: > Called Number (len= 6) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '191' ] 2005-09-26 19:47:54 DEBUG[32173] channel.c: Avoiding initial deadlock for 'Zap/93-1' 2005-09-26 19:47:54 VERBOSE[32193] logger.c: -- Called G2/191 2005-09-26 19:47:54 DEBUG[32193] chan_iax2.c: Created trunk peer for '82.89.96.19:4569' 2005-09-26 19:47:54 DEBUG[32193] chan_iax2.c: Expanded trunk '82.89.96.19:4569' to 6400 bytes 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Protocol Discriminator: Q.931 (8) len=10 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Message type: SETUP ACKNOWLEDGE (13) 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < [2005-09-26 19:47:54 VERBOSE[32181] logger.c: < [182005-09-26 19:47:54 VERBOSE[32181] logger.c: < [18 032005-09 -26 19:47:54 VERBOSE[32181] logger.c: < [18 03 a92005-09-26 19:47:54 VERBOSE[32181] logger.c: < [18 03 a9 832005-09-26 19:47:54 VERBOSE[32181] logger.c: < [18 03 a9 83 9f2005-09-26 19:47:54 VERBOSE[32181] logger.c: < [18 03 a9 83 9f] 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < ChanSel: Reserved 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3 2005-09-26 19:47:54 VERBOSE[32181] logger.c: < Ext: 1 Channel: 31 ] 2005-09-26 19:47:54 VERBOSE[32181] logger.c: -- Processing IE 24 (cs0, Channel Identification) 2005-09-26 19:47:54 DEBUG[32183] chan_iax2.c: Ooh, voice format changed to 1024 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Protocol Discriminator: Q.931 (8) len=13 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Message type: CALL PROCEEDING (2) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 022005-09 -26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 822005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 882005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 88] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Ext: 1 Progress Description: Inband information or appropriate pattern now avail able. (8) ] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 022005-09 -26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 822005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 822005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 82] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Ext: 1 Progress Description: Called equipment is non-ISDN. (2) ] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: -- Processing IE 30 (cs0, Progress Indicator) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: -- Processing IE 30 (cs0, Progress Indicator) 2005-09-26 19:47:56 DEBUG[32181] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/31 span 3 2005-09-26 19:47:56 VERBOSE[32193] logger.c: -- Zap/93-1 is proceeding passing it to IAX2/uptime-16384 2005-09-26 19:47:56 VERBOSE[32193] logger.c: -- Zap/93-1 is making progress passing it to IAX2/uptime-16384 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Protocol Discriminator: Q.931 (8) len=9 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Message type: PROGRESS (3) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e2005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 022005-09 -26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 822005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 882005-09-26 19:47:56 VERBOSE[32181] logger.c: < [1e 02 82 88] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2) 2005-09-26 19:47:56 VERBOSE[32181] logger.c: < Ext: 1 Progress Description: Inband information or appropriate pattern now avail able. (8) ] 2005-09-26 19:47:56 VERBOSE[32181] logger.c: -- Processing IE 30 (cs0, Progress Indicator) 2005-09-26 19:47:56 DEBUG[32181] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/31 span 3 2005-09-26 19:47:56 VERBOSE[32193] logger.c: -- Zap/93-1 is making progress passing it to IAX2/uptime-16384 ========================================================== The ivr starts playing the wellcome message, and then "Press 1 for... press 2 for... ========================================================== 2005-09-26 19:48:43 VERBOSE[32193] logger.c: > Protocol Discriminator: Q.931 (8) len=9 2005-09-26 19:48:43 VERBOSE[32193] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) 2005-09-26 19:48:43 VERBOSE[32193] logger.c: > Message type: INFORMATION (123) 2005-09-26 19:48:43 VERBOSE[32193] logger.c: > [2005-09-26 19:48:43 VERBOSE[32193] logger.c: > [702005-09-26 19:48:43 VERBOSE[32193] logger.c: > [70 022005-09 -26 19:48:43 VERBOSE[32193] logger.c: > [70 02 802005-09-26 19:48:43 VERBOSE[32193] logger.c: > [70 02 80 312005-09-26 19:48:43 VERBOSE[32193] logger.c: > [70 02 80 31] ========================================================== I press "1", the ivr ignore this ========================================================== 2005-09-26 19:48:43 VERBOSE[32193] logger.c: > Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '1' ] 2005-09-26 19:48:43 DTMF[32193] channel.c: Zap/93-1 : 1 2005-09-26 19:48:47 VERBOSE[32193] logger.c: > Protocol Discriminator: Q.931 (8) len=9 2005-09-26 19:48:47 VERBOSE[32193] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) 2005-09-26 19:48:47 VERBOSE[32193] logger.c: > Message type: INFORMATION (123) 2005-09-26 19:48:47 VERBOSE[32193] logger.c: > [2005-09-26 19:48:47 VERBOSE[32193] logger.c: > [702005-09-26 19:48:47 VERBOSE[32193] logger.c: > [70 022005-09 -26 19:48:47 VERBOSE[32193] logger.c: > [70 02 802005-09-26 19:48:47 VERBOSE[32193] logger.c: > [70 02 80 312005-09-26 19:48:47 VERBOSE[32193] logger.c: > [70 02 80 31] 2005-09-26 19:48:47 VERBOSE[32193] logger.c: > Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '1' ] ========================================================== I press "1" again ========================================================== 2005-09-26 19:48:47 DTMF[32193] channel.c: Zap/93-1 : 1 ========================================================== I hangup ========================================================== 2005-09-26 19:48:59 DEBUG[32183] chan_iax2.c: Immediately destroying 16384, having received hangup 2005-09-26 19:48:59 NOTICE[32183] chan_iax2.c: Avoiding IAX destroy deadlock 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/93-1 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Hangup: channel: 93 index = 0, normal = 104, callwait = -1, thirdcall = -1 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call 2005-09-26 19:48:59 VERBOSE[32193] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Outgoing call Proceeding, peerstate Incoming Call Proceeding 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > Protocol Discriminator: Q.931 (8) len=9 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > Message type: DISCONNECT (69) 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > [2005-09-26 19:48:59 VERBOSE[32193] logger.c: > [082005-09-26 19:48:59 VERBOSE[32193] logger.c: > [08 022005-09 -26 19:48:59 VERBOSE[32193] logger.c: > [08 02 812005-09-26 19:48:59 VERBOSE[32193] logger.c: > [08 02 81 902005-09-26 19:48:59 VERBOSE[32193] logger.c: > [08 02 81 90] 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local us er (1) 2005-09-26 19:48:59 VERBOSE[32193] logger.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: disabled echo cancellation on channel 93 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/93-1 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Updated conferencing on 93, with 0 conference users 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/93-1 2005-09-26 19:48:59 DEBUG[32193] chan_zap.c: disabled echo cancellation on channel 93 2005-09-26 19:48:59 VERBOSE[32193] logger.c: -- Hungup 'Zap/93-1' 2005-09-26 19:48:59 DEBUG[32193] app_dial.c: Exiting with DIALSTATUS=CANCEL. 2005-09-26 19:48:59 VERBOSE[32193] logger.c: == Spawn extension (extsrv-in, 191, 3) exited non-zero on 'IAX2/uptime-16384' 2005-09-26 19:48:59 DEBUG[32193] chan_iax2.c: We're hanging up IAX2/uptime-16384 now... 2005-09-26 19:48:59 DEBUG[32193] chan_iax2.c: Really destroying IAX2/uptime-16384 now... 2005-09-26 19:48:59 VERBOSE[32193] logger.c: -- Hungup 'IAX2/uptime-16384' 2005-09-26 19:48:59 VERBOSE[32181] logger.c: < Protocol Discriminator: Q.931 (8) len=5 2005-09-26 19:48:59 VERBOSE[32181] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) 2005-09-26 19:48:59 VERBOSE[32181] logger.c: < Message type: RELEASE (77) 2005-09-26 19:48:59 VERBOSE[32181] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > Protocol Discriminator: Q.931 (8) len=9 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > Message type: RELEASE COMPLETE (90) 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > [2005-09-26 19:48:59 VERBOSE[32181] logger.c: > [082005-09-26 19:48:59 VERBOSE[32181] logger.c: > [08 022005-09 -26 19:48:59 VERBOSE[32181] logger.c: > [08 02 812005-09-26 19:48:59 VERBOSE[32181] logger.c: > [08 02 81 902005-09-26 19:48:59 VERBOSE[32181] logger.c: > [08 02 81 90] 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local us er (1) 2005-09-26 19:48:59 VERBOSE[32181] logger.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] 2005-09-26 19:48:59 VERBOSE[32181] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null 2005-09-26 19:48:59 VERBOSE[32181] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null 2005-09-26 19:49:04 DEBUG[32184] chan_sip.c: Stopping retransmission on '33e7823d217bfabd6ad404797e9c63f6@85.43.54.101' of Request 102: Match Found 2005-09-26 19:49:05 DEBUG[32183] chan_iax2.c: Dropping unused iax2 trunk peer '82.89.96.19:4569'