After a new A2B 1.3 install on TB 2.2 I am experiencing some problem of A2B not "hearing" the pin entered on my SNOM 300.
I am showing here part of my verbose log:
Code:
Aug 16 22:55:58 VERBOSE[3692] logger.c: a2billing.php|1: RESULT : 0
Aug 16 22:55:58 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1798 - Requesting DTMF, CARDNUMBER_LENGTH_MAX 15
Aug 16 22:55:58 DEBUG[3692] channel.c: Scheduling timer at 160 sample intervals
Aug 16 22:55:58 VERBOSE[3692] logger.c: -- Playing 'prepaid-enter-pin-number' (language 'en')
Aug 16 22:56:01 DEBUG[3692] channel.c: Scheduling timer at 0 sample intervals
Aug 16 22:56:05 DEBUG[2804] acl.c: ##### Testing 10.250.5.199 with 192.168.1.0
Aug 16 22:56:05 DEBUG[2804] chan_sip.c: Target address 10.250.5.199 is not local, substituting externip
Aug 16 22:56:07 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1800 - RES DTMF :
Aug 16 22:56:07 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1804 - CARDNUMBER ::>
Aug 16 22:56:07 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1808 - PREPAID-NO-CARD-ENTERED
Aug 16 22:56:09 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1790 - PREPAID-NO-CARD-ENTERED
Aug 16 22:56:09 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1798 - Requesting DTMF, CARDNUMBER_LENGTH_MAX 15
Aug 16 22:56:09 DEBUG[3692] channel.c: Scheduling timer at 160 sample intervals
Aug 16 22:56:09 VERBOSE[3692] logger.c: -- Playing 'prepaid-enter-pin-number' (language 'en')
Aug 16 22:56:11 DEBUG[3692] channel.c: Scheduling timer at 0 sample intervals
Aug 16 22:56:11 DEBUG[3692] channel.c: Scheduling timer at 0 sample intervals
Aug 16 22:56:17 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1800 - RES DTMF :
Aug 16 22:56:17 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1804 - CARDNUMBER ::>
Aug 16 22:56:17 VERBOSE[3692] logger.c: a2billing.php|1: file:Class.A2Billing.php - line:1808 - PREPAID-NO-CARD-ENTERED
Aug 16 22:56:17 DEBUG[3692] channel.c: Scheduling timer at 160 sample intervals
Aug 16 22:56:18 WARNING[2804] chan_sip.c: Maximum retries exceeded on transmission 3c29bcc0e30d-1fbjb2tdomef@snom300-00041325CC35 for seqno 2 (Critical Response)
Aug 16 22:56:18 WARNING[2804] chan_sip.c: Hanging up call 3c29bcc0e30d-1fbjb2tdomef@snom300-00041325CC35 - no reply to our critical packet.
Aug 16 22:56:18 DEBUG[3692] channel.c: Scheduling timer at 0 sample intervals
Aug 16 22:56:18 VERBOSE[3692] logger.c: == Spawn extension (from-internal, 33451709, 1) exited non-zero on 'SIP/909-b7a15438'
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing Macro("SIP/909-b7a15438", "hangupcall") in new stack
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing ResetCDR("SIP/909-b7a15438", "w") in new stack
Aug 16 22:56:18 DEBUG[3692] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Aug 16 22:56:18 DEBUG[3692] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007-08-16 22:55:58','\"device\" <909>','909','33451709','from-internal', 'SIP/909-b7a15438','','ResetCDR','w',20,20,'ANSWERED',3,'','1187297758.4')
Aug 16 22:56:18 DEBUG[3692] app_macro.c: Executed application: ResetCDR
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing NoCDR("SIP/909-b7a15438", "") in new stack
Aug 16 22:56:18 NOTICE[3692] cdr.c: CDR on channel 'SIP/909-b7a15438' not posted
Aug 16 22:56:18 NOTICE[3692] cdr.c: CDR on channel 'SIP/909-b7a15438' lacks end
Aug 16 22:56:18 DEBUG[3692] app_macro.c: Executed application: NoCDR
Aug 16 22:56:18 DEBUG[3692] pbx.c: Expression result is '1'
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing GotoIf("SIP/909-b7a15438", "1?skiprg") in new stack
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Goto (macro-hangupcall,s,6)
Aug 16 22:56:18 DEBUG[3692] app_macro.c: Executed application: GotoIf
Aug 16 22:56:18 DEBUG[3692] pbx.c: Expression result is '1'
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing GotoIf("SIP/909-b7a15438", "1?theend") in new stack
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Goto (macro-hangupcall,s,9)
Aug 16 22:56:18 DEBUG[3692] app_macro.c: Executed application: GotoIf
Aug 16 22:56:18 VERBOSE[3692] logger.c: -- Executing Wait("SIP/909-b7a15438", "5") in new stack
Aug 16 22:56:18 VERBOSE[3692] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/909-b7a15438' in macro 'hangupcall'
Aug 16 22:56:18 VERBOSE[3692] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/909-b7a15438'
How does TB receive and process the DTMF for the pin?
I have followed the wiki write-ups for the 1.3 install and this should be working. (I tested before the A2B install to call out and it worked well - so it's not an issue with the phone.)
Any fast hint would be helpfull!
rgds, devplan