We were recently setting up a new FreePBX system running Asterisk 1.2.6.7 and FreePBX 2.7.0.2 and had tested pinset functionality a while back. To our surprise when we pushed the system live pinsets are not functioning on outbound calls. We are prompted to authenticate, but nothing we enter is accepted. Have enabled DTMF logging and the keys are hitting the Asterisk server. Here is the information below from various output:
DTMF Log
[May 5 08:46:04] DTMF[10100] channel.c: DTMF end ‘6’ received on SIP/500-0000000a, duration 100 ms
[May 5 08:46:04] DTMF[10100] channel.c: DTMF end passthrough ‘6’ on SIP/500-0000000a
[May 5 08:46:04] DTMF[10100] channel.c: DTMF end ‘#’ received on SIP/500-0000000a, duration 100 ms
[May 5 08:46:04] DTMF[10100] channel.c: DTMF end passthrough ‘#’ on SIP/500-0000000a
Asterisk Debug
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
== Using SIP VRTP TOS bits 136
== Using SIP VRTP CoS mark 6
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
– Executing [[email protected]:1] Macro(“SIP/500-0000000a”, “user-callerid,SKIPTTL,”) in new stack
– Executing [[email protected]:1] Set(“SIP/500-0000000a”, “AMPUSER=500”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/500-0000000a”, “0?report”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/500-0000000a”, “1?Set(REALCALLERIDNUM=500)”) in new stack
– Executing [[email protected]:4] Set(“SIP/500-0000000a”, “AMPUSER=500”) in new stack
– Executing [[email protected]:5] Set(“SIP/500-0000000a”, “AMPUSERCIDNAME=Larry Weidig”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/500-0000000a”, “0?report”) in new stack
– Executing [[email protected]:7] Set(“SIP/500-0000000a”, “AMPUSERCID=500”) in new stack
– Executing [[email protected]:8] Set(“SIP/500-0000000a”, “CALLERID(all)=“Larry Weidig” <500>”) in new stack
– Executing [[email protected]:9] ExecIf(“SIP/500-0000000a”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:10] GotoIf(“SIP/500-0000000a”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [[email protected]:19] NoOp(“SIP/500-0000000a”, “Using CallerID “Larry Weidig” <500>”) in new stack
– Executing [[email protected]:2] Macro(“SIP/500-0000000a”, “pinsets,1,1”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/500-0000000a”, “1 = 1?cdr,1”) in new stack
– Goto (macro-pinsets,cdr,1)
– Executing [[email protected]:1] ExecIf(“SIP/500-0000000a”, “1?Authenticate(/etc/asterisk/pinset_1,a)”) in new stack
– <SIP/500-0000000a> Playing ‘agent-pass.slin’ (language ‘en’)
– <SIP/500-0000000a> Playing ‘auth-incorrect.slin’ (language ‘en’)
– <SIP/500-0000000a> Playing ‘vm-goodbye.slin’ (language ‘en’)
== Spawn extension (macro-pinsets, cdr, 1) exited non-zero on ‘SIP/500-0000000a’ in macro ‘pinsets’
== Spawn extension (from-internal, 4520455, 2) exited non-zero on ‘SIP/500-0000000a’
– Executing [[email protected]:1] Macro(“SIP/500-0000000a”, “hangupcall”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/500-0000000a”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:4] GotoIf(“SIP/500-0000000a”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [[email protected]:7] GotoIf(“SIP/500-0000000a”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [[email protected]:9] Hangup(“SIP/500-0000000a”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/500-0000000a’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/500-0000000a’
voip*CLI>
/etc/asterisk/pinset_1
6
We have also tried with 5 digit ticket numbers and that has not made a difference. Any thoughts on how to fix this or at least continue debugging the cause of the issue. The information abouve does not really seem to provide any clue where to look next.
Thanks!