PIN Sets module

After the latest module upgrade for Pin Sets from 13.0.8 to 13.0.9 Pin Sets failed to function as intended. Basically when you make a call that has a pin set assigned to the route it prompts for the password followed by the # key however on first try the system reports “password incorrect” then without hanging up dial the same password again this time it accepts it. I have tested this on two systems with exact system, PBX and module versions with the same result. Is there an option to downgrade the module to the previous version? please note i have also uninstalled and re-installed the module with same results.

Please provide a call trace:
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

You can downgrade with:

fwconsole ma downloadinstall pinsets --tag 13.0.8

@lgaetz, thanks for you reply please see logs below.

[[email protected] ~]# grep 1537548092.50457 /var/log/asterisk/full*
/var/log/asterisk/full:[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Set("SIP/1516-0000b191", "TOUCH_MONITOR=1537548092.50457") in new stack
[[email protected] ~]# grep C-00005794 /var/log/asterisk/full
[2018-09-21 11:41:32] VERBOSE[23205][C-00005794] netsock2.c: Using SIP VIDEO TOS bits 136
[2018-09-21 11:41:32] VERBOSE[23205][C-00005794] netsock2.c: Using SIP VIDEO CoS mark 6
[2018-09-21 11:41:32] VERBOSE[23205][C-00005794] netsock2.c: Using SIP RTP TOS bits 184
[2018-09-21 11:41:32] VERBOSE[23205][C-00005794] netsock2.c: Using SIP RTP CoS mark 5
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Macro("SIP/1516-0000b191", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Set("SIP/1516-0000b191", "TOUCH_MONITOR=1537548092.50457") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Set("SIP/1516-0000b191", "AMPUSER=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] GotoIf("SIP/1516-0000b191", "0?report") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] ExecIf("SIP/1516-0000b191", "1?Set(REALCALLERIDNUM=1516)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] Set("SIP/1516-0000b191", "AMPUSER=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] GotoIf("SIP/1516-0000b191", "0?limit") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Set("SIP/1516-0000b191", "AMPUSERCIDNAME=Sly") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] ExecIf("SIP/1516-0000b191", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:9] GotoIf("SIP/1516-0000b191", "0?report") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:10] Set("SIP/1516-0000b191", "AMPUSERCID=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:11] Set("SIP/1516-0000b191", "__DIAL_OPTIONS=HhTtr") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:12] Set("SIP/1516-0000b191", "CALLERID(all)="Sly" <1516>") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:13] GotoIf("SIP/1516-0000b191", "0?limit") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:14] ExecIf("SIP/1516-0000b191", "1?Set(GROUP(concurrency_limit)=1516)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:15] ExecIf("SIP/1516-0000b191", "0?Set(CHANNEL(language)=)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:16] NoOp("SIP/1516-0000b191", "Macro Depth is 1") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:17] GotoIf("SIP/1516-0000b191", "1?report2:macroerror") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:18] GotoIf("SIP/1516-0000b191", "1?continue") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:37] Set("SIP/1516-0000b191", "CALLERID(number)=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:38] Set("SIP/1516-0000b191", "CALLERID(name)=Sly") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:39] GotoIf("SIP/1516-0000b191", "0?cnum") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:40] Set("SIP/1516-0000b191", "CDR(cnam)=Sly") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:41] Set("SIP/1516-0000b191", "CDR(cnum)=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:42] Set("SIP/1516-0000b191", "CHANNEL(language)=en") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Gosub("SIP/1516-0000b191", "sub-record-check,s,1(out,68763209108,dontcare)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] GotoIf("SIP/1516-0000b191", "0?initialized") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Set("SIP/1516-0000b191", "__REC_STATUS=INITIALIZED") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] Set("SIP/1516-0000b191", "NOW=1537548092") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] Set("SIP/1516-0000b191", "__DAY=21") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] Set("SIP/1516-0000b191", "__MONTH=09") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] Set("SIP/1516-0000b191", "__YEAR=2018") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Set("SIP/1516-0000b191", "__TIMESTR=20180921-114132") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] Set("SIP/1516-0000b191", "__FROMEXTEN=1516") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:9] Set("SIP/1516-0000b191", "__MON_FMT=wav") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:10] NoOp("SIP/1516-0000b191", "Recordings initialized") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:11] ExecIf("SIP/1516-0000b191", "0?Set(ARG3=dontcare)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:12] Set("SIP/1516-0000b191", "REC_POLICY_MODE_SAVE=") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:13] ExecIf("SIP/1516-0000b191", "0?Set(REC_STATUS=NO)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:14] GotoIf("SIP/1516-0000b191", "3?checkaction") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:17] GotoIf("SIP/1516-0000b191", "1?sub-record-check,out,1") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] NoOp("SIP/1516-0000b191", "Outbound Recording Check from 1516 to 68763209108") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Set("SIP/1516-0000b191", "RECMODE=dontcare") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] ExecIf("SIP/1516-0000b191", "1?Goto(routewins)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Gosub("SIP/1516-0000b191", "recordcheck,1(dontcare,out,68763209108)") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] NoOp("SIP/1516-0000b191", "Starting recording check against dontcare") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Goto("SIP/1516-0000b191", "dontcare") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] Return("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] Return("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] Macro("SIP/1516-0000b191", "pinsets,1,0") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Set("SIP/1516-0000b191", "try=1") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] GotoIf("SIP/1516-0000b191", "0?cdr,1") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] GotoIf("SIP/1516-0000b191", "1?auth:return") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-pinsets,s,4)
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] Progress("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] Read("SIP/1516-0000b191", "dtmf,agent-pass,0,n,1,10") in new stack
[2018-09-21 11:41:32] VERBOSE[23110][C-00005794] file.c: <SIP/1516-0000b191> Playing 'agent-pass.ulaw' (language 'en')
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] app_read.c: User entered '909740'
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] GotoIf("SIP/1516-0000b191", "0?return:askpin") in new stack
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-pinsets,s,7)
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Set("SIP/1516-0000b191", "try=2") in new stack
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] GotoIf("SIP/1516-0000b191", "0?hangup") in new stack
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:9] Read("SIP/1516-0000b191", "dtmf,auth-incorrect,0,n,1,10") in new stack
[2018-09-21 11:41:50] VERBOSE[23110][C-00005794] file.c: <SIP/1516-0000b191> Playing 'auth-incorrect.ulaw' (language 'en')
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_read.c: User entered '909740'
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:10] GotoIf("SIP/1516-0000b191", "1?return:askpin") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-pinsets,s,12)
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:12] NoOp("SIP/1516-0000b191", "returning back") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] ExecIf("SIP/1516-0000b191", "0 ?Set(CDR(accountcode)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] Set("SIP/1516-0000b191", "MOHCLASS=default") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] Set("SIP/1516-0000b191", "_NODEST=") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Macro("SIP/1516-0000b191", "dialout-trunk,11,8763209108,,off") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Set("SIP/1516-0000b191", "DIAL_TRUNK=11") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] ExecIf("SIP/1516-0000b191", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] GosubIf("SIP/1516-0000b191", "0?sub-pincheck,s,1()") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] ExecIf("SIP/1516-0000b191", "0?Set(CALLERID(num)=1516)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] GotoIf("SIP/1516-0000b191", "0?disabletrunk,1") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] Set("SIP/1516-0000b191", "DIAL_NUMBER=8763209108") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] Set("SIP/1516-0000b191", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] Set("SIP/1516-0000b191", "OUTBOUND_GROUP=OUT_11") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:9] Set("SIP/1516-0000b191", "DIAL_TRUNK_OPTIONS=T") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:10] GotoIf("SIP/1516-0000b191", "1?nomax") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:12] GotoIf("SIP/1516-0000b191", "0?skipoutcid") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:13] Macro("SIP/1516-0000b191", "outbound-callerid,11") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] NoOp("SIP/1516-0000b191", "1516") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] NoOp("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] NoOp("SIP/1516-0000b191", "off") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] ExecIf("SIP/1516-0000b191", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] ExecIf("SIP/1516-0000b191", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] ExecIf("SIP/1516-0000b191", "0?Set(REALCALLERIDNUM=1516)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:7] ExecIf("SIP/1516-0000b191", "0?Set(AMPUSER=1516)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:8] GotoIf("SIP/1516-0000b191", "1?normcid") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:12] Set("SIP/1516-0000b191", "USEROUTCID=8766328200") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:13] Set("SIP/1516-0000b191", "EMERGENCYCID=") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:14] Set("SIP/1516-0000b191", "TRUNKOUTCID=") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:15] GotoIf("SIP/1516-0000b191", "1?trunkcid") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:20] ExecIf("SIP/1516-0000b191", "0?Set(CALLERID(all)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:21] ExecIf("SIP/1516-0000b191", "1?Set(CALLERID(all)=8766328200)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:22] ExecIf("SIP/1516-0000b191", "0?Set(CALLERID(all)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:23] ExecIf("SIP/1516-0000b191", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:24] ExecIf("SIP/1516-0000b191", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:25] Set("SIP/1516-0000b191", "CDR(outbound_cnum)=8766328200") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:26] Set("SIP/1516-0000b191", "CDR(outbound_cnam)=") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:14] GosubIf("SIP/1516-0000b191", "1?sub-flp-11,s,1()") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] ExecIf("SIP/1516-0000b191", "1?Set(TARGET_FLP_11=9918763209108)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] GotoIf("SIP/1516-0000b191", "1?match") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (sub-flp-11,s,4)
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] Set("SIP/1516-0000b191", "DIAL_NUMBER=9918763209108") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] Return("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:15] Set("SIP/1516-0000b191", "OUTNUM=9918763209108") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:16] Set("SIP/1516-0000b191", "custom=SIP/GXW4108-Lime-P1") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:17] ExecIf("SIP/1516-0000b191", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:18] ExecIf("SIP/1516-0000b191", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:19] Macro("SIP/1516-0000b191", "dialout-trunk-predial-hook,") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] MacroExit("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:20] GotoIf("SIP/1516-0000b191", "0?bypass,1") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:21] ExecIf("SIP/1516-0000b191", "1?Set(CONNECTEDLINE(num,i)=9918763209108)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:22] ExecIf("SIP/1516-0000b191", "1?Set(CONNECTEDLINE(name,i)=CID:8766328200)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:23] ExecIf("SIP/1516-0000b191", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)8766328200)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:24] GotoIf("SIP/1516-0000b191", "0?customtrunk") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:25] Dial("SIP/1516-0000b191", "SIP/GXW4108-Lime-P1/9918763209108,300,Tb(func-apply-sipheaders^s^1)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] netsock2.c: Using SIP RTP TOS bits 184
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] netsock2.c: Using SIP RTP CoS mark 5
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_stack.c: SIP/GXW4108-Lime-P1-0000b192 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] NoOp("SIP/GXW4108-Lime-P1-0000b192", "Applying SIP Headers to channel") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:2] Set("SIP/GXW4108-Lime-P1-0000b192", "SIPHEADERKEYS=") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] ExecIf("SIP/GXW4108-Lime-P1-0000b192", "0?Set(Rheader=1)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] While("SIP/GXW4108-Lime-P1-0000b192", "0") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_while.c: Jumping to priority 8
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:9] ExecIf("SIP/GXW4108-Lime-P1-0000b192", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:10] ExecIf("SIP/GXW4108-Lime-P1-0000b192", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:11] Return("SIP/GXW4108-Lime-P1-0000b192", "") in new stack
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_stack.c: Spawn extension (from-pstn, 68763209108, 1) exited non-zero on 'SIP/GXW4108-Lime-P1-0000b192'
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_stack.c: SIP/GXW4108-Lime-P1-0000b192 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-09-21 11:42:11] VERBOSE[23110][C-00005794] app_dial.c: Called SIP/GXW4108-Lime-P1/9918763209108
[2018-09-21 11:42:14] VERBOSE[23110][C-00005794] app_dial.c: SIP/GXW4108-Lime-P1-0000b192 is ringing
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] app_macro.c: Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'SIP/1516-0000b191' in macro 'dialout-trunk'
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Spawn extension (from-internal, 68763209108, 7) exited non-zero on 'SIP/1516-0000b191'
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] Macro("SIP/1516-0000b191", "hangupcall") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:1] GotoIf("SIP/1516-0000b191", "1?theend") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:3] ExecIf("SIP/1516-0000b191", "0?Set(CDR(recordingfile)=)") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:4] NoOp("SIP/1516-0000b191", "SIP/GXW4108-Lime-P1-0000b192 monior file= ") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:5] AGI("SIP/1516-0000b191", "attendedtransfer-rec-restart.php,SIP/GXW4108-Lime-P1-0000b192,") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] res_agi.c: <SIP/1516-0000b191>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Executing [[email protected]:6] Hangup("SIP/1516-0000b191", "") in new stack
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/1516-0000b191' in macro 'hangupcall'
[2018-09-21 11:42:16] VERBOSE[23110][C-00005794] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1516-0000b191'
[[email protected] ~]#

Looks like a bug. Can you open an issue please at http://issues.freepbx.org/

Problem is at context=macro-pinsets, exten=s, priority=6, missing a " character around the ${ARG1}

  's' =>            1. Set(try=1)                                 [pbx_config]
                    2. GotoIf($[${ARG2} = 1]?cdr,1)               [pbx_config]
                    3. GotoIf($["${DB(AMPUSER/${AMPUSER}/pinless)}" != "NOPASSWD"]?auth:return) [pbx_config]
     [auth]         4. Progress()                                 [pbx_config]
                    5. Read(dtmf,agent-pass,0,n,1,10)             [pbx_config]
                    6. GotoIf($["${DB(PINSETS/${ARG1}/${dtmf})}" = ${ARG1}"]?return:askpin) [pbx_config]
     [askpin]       7. Set(try=$[${try}+1])                       [pbx_config]
                    8. GotoIf($[${try} > 4]?hangup)               [pbx_config]
                    9. Read(dtmf,auth-incorrect,0,n,1,10)         [pbx_config]
     [validate]     10. GotoIf($["${DB(PINSETS/${ARG1}/${dtmf})}" = "${ARG1}"]?return:askpin) [pbx_config]
     [hangup]       11. Hangup()                                  [pbx_config]
     [return]       12. Noop(returning back)                      [pbx_config]

Roll back with command noted earlier as a work around.

@lgaetz. Thanks for your quick response, really appreciate it. Should i uninstall the module first before i roll back per your instructions?

No need to uninstall first.

@lgaetz. Thank you, i created a case per recommendation and submit for review.

1 Like

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.