Intermittent outgoing iax audio issue

Hi all,
I am running Freepbx 2.11.0.2 using IAX trunks supplied by Gradwell.

We have an intermittent issue where we sometimes when we make outbound calls, the other person cannot hear us. If we hang up and ring back, most of the time it is fine.
There doesn’t appear to be any correlation as to when/why it happens, it could be when only one person is on the phone or when 5 people are using the lines.

Also, when we put people on hold, they cannot hear us when we take them off of hold. If we use mute mic, that is fine. We are using Cisco SPA-303 phones.

The voip network is on it’s own ip range and has it’s own dedicated internet connection.

Am happy to provide log files, or whatever is needed, but wondered if anyone had heard of this before?

I have spoken to gradwell who have said there haven’t been any other reported faults pertaining to this (aside from their recent IAX issue).

Any help most gratefully received!

And just as comparison, here is a log snippet from a call that worked fine

[2013-06-21 09:47:15] VERBOSE[1664] netsock2.c: == Using SIP RTP TOS bits 184 [2013-06-21 09:47:15] VERBOSE[1664] netsock2.c: == Using SIP RTP TOS bits 184 [2013-06-21 09:47:15] VERBOSE[1664] netsock2.c: == Using SIP RTP CoS mark 5 [2013-06-21 09:47:15] VERBOSE[1664] netsock2.c: == Using SIP RTP CoS mark 5 [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:1] Macro("SIP/597-00002b48", "user-callerid,LIMIT,") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:1] Macro("SIP/597-00002b48", "user-callerid,LIMIT,") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "TOUCH_MONITOR=1371804435.11199") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "TOUCH_MONITOR=1371804435.11199") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] Set("SIP/597-00002b48", "AMPUSER=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] Set("SIP/597-00002b48", "AMPUSER=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "0?report") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "0?report") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/597-00002b48", "1?Set(REALCALLERIDNUM=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/597-00002b48", "1?Set(REALCALLERIDNUM=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:5] Set("SIP/597-00002b48", "AMPUSER=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:5] Set("SIP/597-00002b48", "AMPUSER=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "AMPUSERCIDNAME=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "AMPUSERCIDNAME=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/597-00002b48", "0?report") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/597-00002b48", "0?report") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] Set("SIP/597-00002b48", "AMPUSERCID=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] Set("SIP/597-00002b48", "AMPUSERCID=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] Set("SIP/597-00002b48", "__DIAL_OPTIONS=tr") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] Set("SIP/597-00002b48", "__DIAL_OPTIONS=tr") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:10] Set("SIP/597-00002b48", "CALLERID(all)="Darren James" <597>") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:10] Set("SIP/597-00002b48", "CALLERID(all)="Darren James" <597>") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/597-00002b48", "0?limit") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/597-00002b48", "0?limit") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/597-00002b48", "1?Set(GROUP(concurrency_limit)=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/597-00002b48", "1?Set(GROUP(concurrency_limit)=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] ExecIf("SIP/597-00002b48", "0?Set(CHANNEL(language)=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] ExecIf("SIP/597-00002b48", "0?Set(CHANNEL(language)=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] GosubIf("SIP/597-00002b48", "7?sub-ccss,s,1(from-internal,9[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] GosubIf("SIP/597-00002b48", "7?sub-ccss,s,1(from-internal,9[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "0?Return()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "0?Return()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] Set("SIP/597-00002b48", "CCSS_SETUP=TRUE") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] Set("SIP/597-00002b48", "CCSS_SETUP=TRUE") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/597-00002b48", "0?monitor_config,1(from-internal,9[Number Redacted]):monitor_default,1(from-internal,9[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/597-00002b48", "0?monitor_config,1(from-internal,9[Number Redacted]):monitor_default,1(from-internal,9[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/597-00002b48", "0?is_exten") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/597-00002b48", "0?is_exten") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [monitor[email protected]:2] StackPop("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] StackPop("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] Return("SIP/597-00002b48", "FALSE") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] Return("SIP/597-00002b48", "FALSE") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/597-00002b48", "1?continue") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/597-00002b48", "1?continue") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-user-callerid,s,28) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-user-callerid,s,28) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:28] Set("SIP/597-00002b48", "CALLERID(number)=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:28] Set("SIP/597-00002b48", "CALLERID(number)=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:29] Set("SIP/597-00002b48", "CALLERID(name)=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:29] Set("SIP/597-00002b48", "CALLERID(name)=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:30] Set("SIP/597-00002b48", "CDR(cnum)=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:30] Set("SIP/597-00002b48", "CDR(cnum)=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:31] Set("SIP/597-00002b48", "CDR(cnam)=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:31] Set("SIP/597-00002b48", "CDR(cnam)=Darren James") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:32] Set("SIP/597-00002b48", "CHANNEL(language)=en") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:32] Set("SIP/597-00002b48", "CHANNEL(language)=en") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:2] Set("SIP/597-00002b48", "MOHCLASS=none") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:2] Set("SIP/597-00002b48", "MOHCLASS=none") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:3] ExecIf("SIP/597-00002b48", "1?Set(TRUNKCIDOVERRIDE=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:3] ExecIf("SIP/597-00002b48", "1?Set(TRUNKCIDOVERRIDE=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:4] Set("SIP/597-00002b48", "_NODEST=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:4] Set("SIP/597-00002b48", "_NODEST=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:5] Gosub("SIP/597-00002b48", "sub-record-check,s,1(out,9[Number Redacted],)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:5] Gosub("SIP/597-00002b48", "sub-record-check,s,1(out,9[Number Redacted],)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "REC_POLICY_MODE_SAVE=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "REC_POLICY_MODE_SAVE=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/597-00002b48", "1?check") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/597-00002b48", "1?check") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,s,7) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,s,7) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] Set("SIP/597-00002b48", "__MON_FMT=wav") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] Set("SIP/597-00002b48", "__MON_FMT=wav") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/597-00002b48", "1?next") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/597-00002b48", "1?next") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,s,11) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,s,11) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] ExecIf("SIP/597-00002b48", "0?Return()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] ExecIf("SIP/597-00002b48", "0?Return()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/597-00002b48", "0?Set(__REC_POLICY_MODE=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/597-00002b48", "0?Set(__REC_POLICY_MODE=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/597-00002b48", "0?out,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/597-00002b48", "0?out,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] Set("SIP/597-00002b48", "__REC_STATUS=INITIALIZED") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] Set("SIP/597-00002b48", "__REC_STATUS=INITIALIZED") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] Set("SIP/597-00002b48", "NOW=1371804435") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] Set("SIP/597-00002b48", "NOW=1371804435") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] Set("SIP/597-00002b48", "__DAY=21") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] Set("SIP/597-00002b48", "__DAY=21") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] Set("SIP/597-00002b48", "__MONTH=06") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] Set("SIP/597-00002b48", "__MONTH=06") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] Set("SIP/597-00002b48", "__YEAR=2013") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] Set("SIP/597-00002b48", "__YEAR=2013") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] Set("SIP/597-00002b48", "__TIMESTR=20130621-094715") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] Set("SIP/597-00002b48", "__TIMESTR=20130621-094715") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:20] Set("SIP/597-00002b48", "__FROMEXTEN=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:20] Set("SIP/597-00002b48", "__FROMEXTEN=597") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:21] Set("SIP/597-00002b48", "__CALLFILENAME=out-9[Number Redacted]-597-20130621-094715-1371804435.11199") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:21] Set("SIP/597-00002b48", "__CALLFILENAME=out-9[Number Redacted]-597-20130621-094715-1371804435.11199") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]ord-check:22] Goto("SIP/597-00002b48", "out,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:22] Goto("SIP/597-00002b48", "out,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,out,1) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (sub-record-check,out,1) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GosubIf("SIP/597-00002b48", "0?record,1(exten,9[Number Redacted],597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GosubIf("SIP/597-00002b48", "0?record,1(exten,9[Number Redacted],597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] Return("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] Return("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:6] Macro("SIP/597-00002b48", "dialout-trunk,2,[Number Redacted],,off") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [9[Number Redacted]@from-internal:6] Macro("SIP/597-00002b48", "dialout-trunk,2,[Number Redacted],,off") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "DIAL_TRUNK=2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("SIP/597-00002b48", "DIAL_TRUNK=2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GosubIf("SIP/597-00002b48", "0?sub-pincheck,s,1()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] GosubIf("SIP/597-00002b48", "0?sub-pincheck,s,1()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "0?disabletrunk,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "0?disabletrunk,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] Set("SIP/597-00002b48", "DIAL_NUMBER=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] Set("SIP/597-00002b48", "DIAL_NUMBER=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:5] Set("SIP/597-00002b48", "DIAL_TRUNK_OPTIONS=tr") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:5] Set("SIP/597-00002b48", "DIAL_TRUNK_OPTIONS=tr") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "OUTBOUND_GROUP=OUT_2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "OUTBOUND_GROUP=OUT_2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/597-00002b48", "0?nomax") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/597-00002b48", "0?nomax") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/597-00002b48", "0?chanfull") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/597-00002b48", "0?chanfull") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/597-00002b48", "0?skipoutcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/597-00002b48", "0?skipoutcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:10] Set("SIP/597-00002b48", "DIAL_TRUNK_OPTIONS=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:10] Set("SIP/597-00002b48", "DIAL_TRUNK_OPTIONS=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] Macro("SIP/597-00002b48", "outbound-callerid,2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:11] Macro("SIP/597-00002b48", "outbound-callerid,2") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "0?Set(CALLERPRES()=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/597-00002b48", "0?Set(CALLERPRES()=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] ExecIf("SIP/597-00002b48", "0?Set(REALCALLERIDNUM=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:2] ExecIf("SIP/597-00002b48", "0?Set(REALCALLERIDNUM=597)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "1?normcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/597-00002b48", "1?normcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-outbound-callerid,s,6) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-outbound-callerid,s,6) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "USEROUTCID=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:6] Set("SIP/597-00002b48", "USEROUTCID=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] Set("SIP/597-00002b48", "EMERGENCYCID=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:7] Set("SIP/597-00002b48", "EMERGENCYCID=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] Set("SIP/597-00002b48", "TRUNKOUTCID=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:8] Set("SIP/597-00002b48", "TRUNKOUTCID=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/597-00002b48", "1?trunkcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/597-00002b48", "1?trunkcid") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-outbound-callerid,s,14) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Goto (macro-outbound-callerid,s,14) [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] ExecIf("SIP/597-00002b48", "1?Set(CALLERID(all)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] ExecIf("SIP/597-00002b48", "1?Set(CALLERID(all)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] ExecIf("SIP/597-00002b48", "0?Set(CALLERID(all)=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] ExecIf("SIP/597-00002b48", "0?Set(CALLERID(all)=)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/597-00002b48", "1?Set(CALLERID(all)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/597-00002b48", "1?Set(CALLERID(all)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] ExecIf("SIP/597-00002b48", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] ExecIf("SIP/597-00002b48", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] Set("SIP/597-00002b48", "CDR(outbound_cnum)=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] Set("SIP/597-00002b48", "CDR(outbound_cnum)=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] Set("SIP/597-00002b48", "CDR(outbound_cnam)=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] Set("SIP/597-00002b48", "CDR(outbound_cnam)=") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] GosubIf("SIP/597-00002b48", "0?sub-flp-2,s,1()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:12] GosubIf("SIP/597-00002b48", "0?sub-flp-2,s,1()") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] Set("SIP/597-00002b48", "OUTNUM=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:13] Set("SIP/597-00002b48", "OUTNUM=[Number Redacted]") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] Set("SIP/597-00002b48", "custom=IAX2/gradwell") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:14] Set("SIP/597-00002b48", "custom=IAX2/gradwell") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] ExecIf("SIP/597-00002b48", "1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none))") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:15] ExecIf("SIP/597-00002b48", "1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none))") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/597-00002b48", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)M(confirm))") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/597-00002b48", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)M(confirm))") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] Macro("SIP/597-00002b48", "dialout-trunk-predial-hook,") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:17] Macro("SIP/597-00002b48", "dialout-trunk-predial-hook,") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] MacroExit("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] MacroExit("SIP/597-00002b48", "") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] GotoIf("SIP/597-00002b48", "0?bypass,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:18] GotoIf("SIP/597-00002b48", "0?bypass,1") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] ExecIf("SIP/597-00002b48", "1?Set(CONNECTEDLINE(num,i)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:19] ExecIf("SIP/597-00002b48", "1?Set(CONNECTEDLINE(num,i)=[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:20] ExecIf("SIP/597-00002b48", "1?Set(CONNECTEDLINE(name,i)=CID:[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:20] ExecIf("SIP/597-00002b48", "1?Set(CONNECTEDLINE(name,i)=CID:[Number Redacted])") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:21] GotoIf("SIP/597-00002b48", "0?customtrunk") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:21] GotoIf("SIP/597-00002b48", "0?customtrunk") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:22] Dial("SIP/597-00002b48", "IAX2/gradwell/[Number Redacted],300,M(setmusic^none)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] pbx.c: -- Executing [[email protected]:22] Dial("SIP/597-00002b48", "IAX2/gradwell/[Number Redacted],300,M(setmusic^none)") in new stack [2013-06-21 09:47:15] VERBOSE[4875] app_dial.c: -- Called IAX2/gradwell/[Number Redacted] [2013-06-21 09:47:15] VERBOSE[4875] app_dial.c: -- Called IAX2/gradwell/[Number Redacted] [2013-06-21 09:47:15] VERBOSE[1657] chan_iax2.c: -- Call accepted by 109.224.241.254 (format ulaw) [2013-06-21 09:47:15] VERBOSE[1657] chan_iax2.c: -- Call accepted by 109.224.241.254 (format ulaw) [2013-06-21 09:47:15] VERBOSE[1657] chan_iax2.c: -- Format for call is (ulaw) [2013-06-21 09:47:15] VERBOSE[1657] chan_iax2.c: -- Format for call is (ulaw) [2013-06-21 09:47:16] NOTICE[4877] manager.c: Seems to have passed... [2013-06-21 09:47:16] NOTICE[4877] manager.c: Seems to have passed... [2013-06-21 09:47:17] NOTICE[4885] manager.c: Seems to have passed... [2013-06-21 09:47:17] NOTICE[4885] manager.c: Seems to have passed... [2013-06-21 09:47:20] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 is ringing [2013-06-21 09:47:20] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 is ringing [2013-06-21 09:47:20] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 is making progress passing it to SIP/597-00002b48 [2013-06-21 09:47:20] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 is making progress passing it to SIP/597-00002b48 [2013-06-21 09:47:23] NOTICE[4888] manager.c: Seems to have passed... [2013-06-21 09:47:23] NOTICE[4888] manager.c: Seems to have passed... [2013-06-21 09:47:25] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 stopped sounds [2013-06-21 09:47:25] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 stopped sounds [2013-06-21 09:47:25] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 answered SIP/597-00002b48 [2013-06-21 09:47:25] VERBOSE[4875] app_dial.c: -- IAX2/gradwell-1831 answered SIP/597-00002b48 [2013-06-21 09:47:25] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("IAX2/gradwell-1831", "CHANNEL(musicclass)=none") in new stack [2013-06-21 09:47:25] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Set("IAX2/gradwell-1831", "CHANNEL(musicclass)=none") in new stack [2013-06-21 09:47:30] NOTICE[4896] manager.c: Seems to have passed... [2013-06-21 09:47:30] NOTICE[4896] manager.c: Seems to have passed... [2013-06-21 09:47:34] NOTICE[4904] manager.c: Seems to have passed... [2013-06-21 09:47:34] NOTICE[4904] manager.c: Seems to have passed... [2013-06-21 09:47:34] NOTICE[4906] manager.c: Seems to have passed... [2013-06-21 09:47:34] NOTICE[4906] manager.c: Seems to have passed... [2013-06-21 09:47:37] NOTICE[4909] manager.c: Seems to have passed... [2013-06-21 09:47:37] NOTICE[4909] manager.c: Seems to have passed... [2013-06-21 09:47:45] NOTICE[4917] manager.c: Seems to have passed... [2013-06-21 09:47:45] NOTICE[4917] manager.c: Seems to have passed... [2013-06-21 09:47:47] NOTICE[4925] manager.c: Seems to have passed... [2013-06-21 09:47:47] NOTICE[4925] manager.c: Seems to have passed... [2013-06-21 09:47:52] NOTICE[4928] manager.c: Seems to have passed... [2013-06-21 09:47:52] NOTICE[4928] manager.c: Seems to have passed... [2013-06-21 09:47:59] NOTICE[4936] manager.c: Seems to have passed... [2013-06-21 09:47:59] NOTICE[4936] manager.c: Seems to have passed... [2013-06-21 09:48:06] NOTICE[4944] manager.c: Seems to have passed... [2013-06-21 09:48:06] NOTICE[4944] manager.c: Seems to have passed... [2013-06-21 09:48:13] NOTICE[4952] manager.c: Seems to have passed... [2013-06-21 09:48:13] NOTICE[4952] manager.c: Seems to have passed... [2013-06-21 09:48:17] NOTICE[4960] manager.c: Seems to have passed... [2013-06-21 09:48:17] NOTICE[4960] manager.c: Seems to have passed... [2013-06-21 09:48:19] NOTICE[4963] manager.c: Seems to have passed... [2013-06-21 09:48:19] NOTICE[4963] manager.c: Seems to have passed... [2013-06-21 09:48:20] NOTICE[4965] manager.c: Seems to have passed... [2013-06-21 09:48:20] NOTICE[4965] manager.c: Seems to have passed... [2013-06-21 09:48:21] NOTICE[4968] manager.c: Seems to have passed... [2013-06-21 09:48:21] NOTICE[4968] manager.c: Seems to have passed... [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Macro("SIP/597-00002b48", "hangupcall,") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] Macro("SIP/597-00002b48", "hangupcall,") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/597-00002b48", "1?theend") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/597-00002b48", "1?theend") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/597-00002b48", "0?Set(CDR(recordingfile)=)") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/597-00002b48", "0?Set(CDR(recordingfile)=)") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] Hangup("SIP/597-00002b48", "") in new stack [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: -- Executing [[email protected]:4] Hangup("SIP/597-00002b48", "") in new stack [2013-06-21 09:48:21] VERBOSE[4875] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/597-00002b48' in macro 'hangupcall' [2013-06-21 09:48:21] VERBOSE[4875] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/597-00002b48' in macro 'hangupcall' [2013-06-21 09:48:21] VERBOSE[4875] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/597-00002b48' [2013-06-21 09:48:21] VERBOSE[4875] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/597-00002b48' [2013-06-21 09:48:21] VERBOSE[4875] chan_iax2.c: -- Hungup 'IAX2/gradwell-1831' [2013-06-21 09:48:21] VERBOSE[4875] chan_iax2.c: -- Hungup 'IAX2/gradwell-1831' [2013-06-21 09:48:21] VERBOSE[4875] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/597-00002b48' in macro 'dialout-trunk' [2013-06-21 09:48:21] VERBOSE[4875] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/597-00002b48' in macro 'dialout-trunk' [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: == Spawn extension (from-internal, 9[Number Redacted], 6) exited non-zero on 'SIP/597-00002b48' [2013-06-21 09:48:21] VERBOSE[4875] pbx.c: == Spawn extension (from-internal, 9[Number Redacted], 6) exited non-zero on 'SIP/597-00002b48'

It has just happened again and so have taken a grab of the log.
The only thing I have changed is redacted the source/destination telephone numbers.

[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:1] Macro(“SIP/506-00002b40”, “user-callerid,LIMIT,”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:1] Macro(“SIP/506-00002b40”, “user-callerid,LIMIT,”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “TOUCH_MONITOR=1371803803.11189”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “TOUCH_MONITOR=1371803803.11189”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] Set(“SIP/506-00002b40”, “AMPUSER=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] Set(“SIP/506-00002b40”, “AMPUSER=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “0?report”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “0?report”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/506-00002b40”, “1?Set(REALCALLERIDNUM=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/506-00002b40”, “1?Set(REALCALLERIDNUM=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:5] Set(“SIP/506-00002b40”, “AMPUSER=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:5] Set(“SIP/506-00002b40”, “AMPUSER=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “AMPUSERCIDNAME=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “AMPUSERCIDNAME=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/506-00002b40”, “0?report”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/506-00002b40”, “0?report”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] Set(“SIP/506-00002b40”, “AMPUSERCID=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] Set(“SIP/506-00002b40”, “AMPUSERCID=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] Set(“SIP/506-00002b40”, “__DIAL_OPTIONS=tr”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] Set(“SIP/506-00002b40”, “__DIAL_OPTIONS=tr”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:10] Set(“SIP/506-00002b40”, “CALLERID(all)=“Oli Lea” <506>”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:10] Set(“SIP/506-00002b40”, “CALLERID(all)=“Oli Lea” <506>”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/506-00002b40”, “0?limit”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/506-00002b40”, “0?limit”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/506-00002b40”, “1?Set(GROUP(concurrency_limit)=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/506-00002b40”, “1?Set(GROUP(concurrency_limit)=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] ExecIf(“SIP/506-00002b40”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] ExecIf(“SIP/506-00002b40”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] GosubIf(“SIP/506-00002b40”, “7?sub-ccss,s,1(from-internal,[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] GosubIf(“SIP/506-00002b40”, “7?sub-ccss,s,1(from-internal,[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “0?Return()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “0?Return()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] Set(“SIP/506-00002b40”, “CCSS_SETUP=TRUE”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] Set(“SIP/506-00002b40”, “CCSS_SETUP=TRUE”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/506-00002b40”, “0?monitor_config,1(from-internal,[Number Redacted]):monitor_default,1(from-internal,[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/506-00002b40”, “0?monitor_config,1(from-internal,[Number Redacted]):monitor_default,1(from-internal,[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/506-00002b40”, “0?is_exten”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/506-00002b40”, “0?is_exten”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] StackPop(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] StackPop(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] Return(“SIP/506-00002b40”, “FALSE”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] Return(“SIP/506-00002b40”, “FALSE”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/506-00002b40”, “1?continue”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/506-00002b40”, “1?continue”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:28] Set(“SIP/506-00002b40”, “CALLERID(number)=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:28] Set(“SIP/506-00002b40”, “CALLERID(number)=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:29] Set(“SIP/506-00002b40”, “CALLERID(name)=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:29] Set(“SIP/506-00002b40”, “CALLERID(name)=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:30] Set(“SIP/506-00002b40”, “CDR(cnum)=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:30] Set(“SIP/506-00002b40”, “CDR(cnum)=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:31] Set(“SIP/506-00002b40”, “CDR(cnam)=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:31] Set(“SIP/506-00002b40”, “CDR(cnam)=Oli Lea”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:32] Set(“SIP/506-00002b40”, “CHANNEL(language)=en”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:32] Set(“SIP/506-00002b40”, “CHANNEL(language)=en”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:2] Set(“SIP/506-00002b40”, “MOHCLASS=none”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:2] Set(“SIP/506-00002b40”, “MOHCLASS=none”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:3] ExecIf(“SIP/506-00002b40”, “1?Set(TRUNKCIDOVERRIDE=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:3] ExecIf(“SIP/506-00002b40”, “1?Set(TRUNKCIDOVERRIDE=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:4] Set(“SIP/506-00002b40”, “_NODEST=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:4] Set(“SIP/506-00002b40”, “_NODEST=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:5] Gosub(“SIP/506-00002b40”, “sub-record-check,s,1(out,[Number Redacted],)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:5] Gosub(“SIP/506-00002b40”, “sub-record-check,s,1(out,[Number Redacted],)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/506-00002b40”, “1?check”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/506-00002b40”, “1?check”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,s,7)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,s,7)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] Set(“SIP/506-00002b40”, “__MON_FMT=wav”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] Set(“SIP/506-00002b40”, “__MON_FMT=wav”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/506-00002b40”, “1?next”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/506-00002b40”, “1?next”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,s,11)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,s,11)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/506-00002b40”, “0?Return()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/506-00002b40”, “0?Return()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/506-00002b40”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/506-00002b40”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/506-00002b40”, “0?out,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/506-00002b40”, “0?out,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] Set(“SIP/506-00002b40”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] Set(“SIP/506-00002b40”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] Set(“SIP/506-00002b40”, “NOW=1371803803”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] Set(“SIP/506-00002b40”, “NOW=1371803803”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] Set(“SIP/506-00002b40”, “__DAY=21”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] Set(“SIP/506-00002b40”, “__DAY=21”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] Set(“SIP/506-00002b40”, “__MONTH=06”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] Set(“SIP/506-00002b40”, “__MONTH=06”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] Set(“SIP/506-00002b40”, “__YEAR=2013”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] Set(“SIP/506-00002b40”, “__YEAR=2013”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] Set(“SIP/506-00002b40”, “__TIMESTR=20130621-093643”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] Set(“SIP/506-00002b40”, “__TIMESTR=20130621-093643”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:20] Set(“SIP/506-00002b40”, “__FROMEXTEN=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:20] Set(“SIP/506-00002b40”, “__FROMEXTEN=506”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:21] Set(“SIP/506-00002b40”, “__CALLFILENAME=out-[Number Redacted]-506-20130621-093643-1371803803.11189”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:21] Set(“SIP/506-00002b40”, “__CALLFILENAME=out-[Number Redacted]-506-20130621-093643-1371803803.11189”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:22] Goto(“SIP/506-00002b40”, “out,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:22] Goto(“SIP/506-00002b40”, “out,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,out,1)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (sub-record-check,out,1)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/506-00002b40”, “0?record,1(exten,[Number Redacted],506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/506-00002b40”, “0?record,1(exten,[Number Redacted],506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] Return(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] Return(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:6] Macro(“SIP/506-00002b40”, “dialout-trunk,2,[Number Redacted],off”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[Number Redacted]@from-internal:6] Macro(“SIP/506-00002b40”, “dialout-trunk,2,[Number Redacted],off”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “DIAL_TRUNK=2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“SIP/506-00002b40”, “DIAL_TRUNK=2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/506-00002b40”, “0?sub-pincheck,s,1()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/506-00002b40”, “0?sub-pincheck,s,1()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “0?disabletrunk,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “0?disabletrunk,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] Set(“SIP/506-00002b40”, “DIAL_NUMBER=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] Set(“SIP/506-00002b40”, “DIAL_NUMBER=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:5] Set(“SIP/506-00002b40”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:5] Set(“SIP/506-00002b40”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/506-00002b40”, “0?nomax”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/506-00002b40”, “0?nomax”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/506-00002b40”, “0?chanfull”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/506-00002b40”, “0?chanfull”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/506-00002b40”, “0?skipoutcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/506-00002b40”, “0?skipoutcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:10] Set(“SIP/506-00002b40”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:10] Set(“SIP/506-00002b40”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] Macro(“SIP/506-00002b40”, “outbound-callerid,2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:11] Macro(“SIP/506-00002b40”, “outbound-callerid,2”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERPRES()=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERPRES()=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/506-00002b40”, “0?Set(REALCALLERIDNUM=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/506-00002b40”, “0?Set(REALCALLERIDNUM=506)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “1?normcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/506-00002b40”, “1?normcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “USEROUTCID=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:6] Set(“SIP/506-00002b40”, “USEROUTCID=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] Set(“SIP/506-00002b40”, “EMERGENCYCID=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:7] Set(“SIP/506-00002b40”, “EMERGENCYCID=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] Set(“SIP/506-00002b40”, “TRUNKOUTCID=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:8] Set(“SIP/506-00002b40”, “TRUNKOUTCID=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/506-00002b40”, “1?trunkcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/506-00002b40”, “1?trunkcid”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/506-00002b40”, “1?Set(CALLERID(all)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/506-00002b40”, “1?Set(CALLERID(all)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERID(all)=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERID(all)=)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/506-00002b40”, “1?Set(CALLERID(all)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/506-00002b40”, “1?Set(CALLERID(all)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/506-00002b40”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] Set(“SIP/506-00002b40”, “CDR(outbound_cnum)=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] Set(“SIP/506-00002b40”, “CDR(outbound_cnum)=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] Set(“SIP/506-00002b40”, “CDR(outbound_cnam)=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] Set(“SIP/506-00002b40”, “CDR(outbound_cnam)=”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/506-00002b40”, “0?sub-flp-2,s,1()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/506-00002b40”, “0?sub-flp-2,s,1()”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] Set(“SIP/506-00002b40”, “OUTNUM=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:13] Set(“SIP/506-00002b40”, “OUTNUM=[Number Redacted]”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] Set(“SIP/506-00002b40”, “custom=IAX2/gradwell”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:14] Set(“SIP/506-00002b40”, “custom=IAX2/gradwell”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/506-00002b40”, “1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none))”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/506-00002b40”, “1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none))”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/506-00002b40”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)M(confirm))”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/506-00002b40”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)M(confirm))”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] Macro(“SIP/506-00002b40”, “dialout-trunk-predial-hook,”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:17] Macro(“SIP/506-00002b40”, “dialout-trunk-predial-hook,”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] MacroExit(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] MacroExit(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/506-00002b40”, “0?bypass,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/506-00002b40”, “0?bypass,1”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/506-00002b40”, “1?Set(CONNECTEDLINE(num,i)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/506-00002b40”, “1?Set(CONNECTEDLINE(num,i)=[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/506-00002b40”, “1?Set(CONNECTEDLINE(name,i)=CID:[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/506-00002b40”, “1?Set(CONNECTEDLINE(name,i)=CID:[Number Redacted])”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/506-00002b40”, “0?customtrunk”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/506-00002b40”, “0?customtrunk”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:22] Dial(“SIP/506-00002b40”, “IAX2/gradwell/[Number Redacted],300,M(setmusic^none)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] pbx.c: – Executing [[email protected]:22] Dial(“SIP/506-00002b40”, “IAX2/gradwell/[Number Redacted],300,M(setmusic^none)”) in new stack
[2013-06-21 09:36:43] VERBOSE[4095] app_dial.c: – Called IAX2/gradwell/[Number Redacted]
[2013-06-21 09:36:43] VERBOSE[4095] app_dial.c: – Called IAX2/gradwell/[Number Redacted]
[2013-06-21 09:36:43] VERBOSE[1651] chan_iax2.c: – Call accepted by 109.224.241.254 (format ulaw)
[2013-06-21 09:36:43] VERBOSE[1651] chan_iax2.c: – Call accepted by 109.224.241.254 (format ulaw)
[2013-06-21 09:36:43] VERBOSE[1651] chan_iax2.c: – Format for call is (ulaw)
[2013-06-21 09:36:43] VERBOSE[1651] chan_iax2.c: – Format for call is (ulaw)
[2013-06-21 09:36:47] NOTICE[4097] manager.c: Seems to have passed…
[2013-06-21 09:36:47] NOTICE[4097] manager.c: Seems to have passed…
[2013-06-21 09:36:50] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 is ringing
[2013-06-21 09:36:50] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 is ringing
[2013-06-21 09:36:50] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 is making progress passing it to SIP/506-00002b40
[2013-06-21 09:36:50] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 is making progress passing it to SIP/506-00002b40
[2013-06-21 09:36:55] NOTICE[4105] manager.c: Seems to have passed…
[2013-06-21 09:36:55] NOTICE[4105] manager.c: Seems to have passed…
[2013-06-21 09:36:56] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 stopped sounds
[2013-06-21 09:36:56] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 stopped sounds
[2013-06-21 09:36:56] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 answered SIP/506-00002b40
[2013-06-21 09:36:56] VERBOSE[4095] app_dial.c: – IAX2/gradwell-1682 answered SIP/506-00002b40
[2013-06-21 09:36:56] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“IAX2/gradwell-1682”, “CHANNEL(musicclass)=none”) in new stack
[2013-06-21 09:36:56] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Set(“IAX2/gradwell-1682”, “CHANNEL(musicclass)=none”) in new stack
[2013-06-21 09:37:02] NOTICE[4113] manager.c: Seems to have passed…
[2013-06-21 09:37:02] NOTICE[4113] manager.c: Seems to have passed…
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Macro(“SIP/506-00002b40”, “hangupcall,”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] Macro(“SIP/506-00002b40”, “hangupcall,”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/506-00002b40”, “1?theend”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/506-00002b40”, “1?theend”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/506-00002b40”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/506-00002b40”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/506-00002b40”, “”) in new stack
[2013-06-21 09:37:04] VERBOSE[4095] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/506-00002b40’ in macro ‘hangupcall’
[2013-06-21 09:37:04] VERBOSE[4095] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/506-00002b40’ in macro ‘hangupcall’
[2013-06-21 09:37:04] VERBOSE[4095] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/506-00002b40’
[2013-06-21 09:37:04] VERBOSE[4095] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/506-00002b40’
[2013-06-21 09:37:04] VERBOSE[4095] chan_iax2.c: – Hungup ‘IAX2/gradwell-1682’
[2013-06-21 09:37:04] VERBOSE[4095] chan_iax2.c: – Hungup ‘IAX2/gradwell-1682’
[2013-06-21 09:37:04] VERBOSE[4095] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/506-00002b40’ in macro ‘dialout-trunk’
[2013-06-21 09:37:04] VERBOSE[4095] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/506-00002b40’ in macro ‘dialout-trunk’
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: == Spawn extension (from-internal, [Number Redacted], 6) exited non-zero on ‘SIP/506-00002b40’
[2013-06-21 09:37:04] VERBOSE[4095] pbx.c: == Spawn extension (from-internal, [Number Redacted], 6) exited non-zero on ‘SIP/506-00002b40’
[2013-06-21 09:37:09] NOTICE[4121] manager.c: Seems to have passed…

Apologies for the bump - still having this issue and can see no logical reason why it is happening, and google isn’t providing much in the way of assistance either.

Hoping someone may have some input on this.

this smells like a firewall issue to me.

Hiya,
Thanks for the reply.
I have all the needed ports forwarded, but thought if it was a firewall issue, then it would be on every call, rather than just intermittent calls?
Current ports I have forwarded are
3478, 4569, 5060-5061, 10000-10500 (all UDP).
Is there something I’ve missed out?

Again, thanks for your reply.

IAX only uses 4569 and doesn’t need ports opened, it supports full NAT-T.

I doubt it is a firewall issue unless session timeout set too low in Firewall.

Are you using a real firewall or some consumer rubbish?

stuff like this is often session timer related. look at the udp session timer.