LOG:
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/203-00000005", "0?out,1") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/203-00000005", "REC_STATUS=INITIALIZED") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/203-00000005", "NOW=1406753933") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/203-00000005", "DAY=30") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/203-00000005", "MONTH=07") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/203-00000005", "YEAR=2014") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/203-00000005", "TIMESTR=20140730-215853") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/203-00000005", "FROMEXTEN=203") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/203-00000005", "CALLFILENAME=out-652317XXX-203-20140730-215853-1406753933.5") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/203-00000005", "out,1") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Goto (sub-record-check,out,1)
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("SIP/203-00000005", "1?Set(REC_POLICY_MODE=dontcare)") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("SIP/203-00000005", "0?record,1(exten,652317XXX,203)") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [out@sub-record-check:3] Return("SIP/203-00000005", "") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [652317XXX@from-internal:6] Macro("SIP/203-00000005", "dialout-trunk,2,652317XXX,,off") in new stack
[2014-07-30 21:58:53] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/203-00000005", "DIAL_TRUNK=2") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/203-00000005", "0?sub-pincheck,s,1()") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/203-00000005", "0?disabletrunk,1") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/203-00000005", "DIAL_NUMBER=652317XXX") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/203-00000005", "DIAL_TRUNK_OPTIONS=tr") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/203-00000005", "OUTBOUND_GROUP=OUT_2") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/203-00000005", "1?nomax") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/203-00000005", "0?skipoutcid") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/203-00000005", "DIAL_TRUNK_OPTIONS=") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/203-00000005", "outbound-callerid,2") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/203-00000005", "0?Set(CALLERPRES()=)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/203-00000005", "0?Set(REALCALLERIDNUM=203)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/203-00000005", "1?normcid") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/203-00000005", "USEROUTCID=") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/203-00000005", "EMERGENCYCID=") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/203-00000005", "TRUNKOUTCID=+34915524622") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/203-00000005", "1?trunkcid") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/203-00000005", "1?Set(CALLERID(all)=+34915524622)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/203-00000005", "0?Set(CALLERID(all)=)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/203-00000005", "0?Set(CALLERID(all)=)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/203-00000005", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("SIP/203-00000005", "CDR(outbound_cnum)=+34915524622") in new stack
[2014-07-30 21:58:54] VERBOSE[C-00000004] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("SIP/203-00000005", "CDR(outbound_cnam)=") in new stack