No audio

My configuration is Cisco 7961 <–> Asterisk <–> 2811 <–> Level3 (using SIP all the way).

My main issue is that when I specify NAT=no and Public IP in the Asterisk SIP Settings on FreePBX, the calls complete fine except that the internal IP address is visible in the FROM: tag. If I change to NAT=yes and Static IP in the settings, then the FROM: in the INVITE is formatted correctly but there is no audio and the call will automatically drop about 10sec after connection.

The additional problem is that internal calls, which shouldn’t even touch the 2811, follow the same no audio behavior.

I have googled and searched so much that I am completely confused and am not sure if the issue is in the Asterisk, 2811, or both. I can provide any config info requested.

2811 - Version 12.4(15)T12
Asterisk - 1.8.11
freePBX - 2.10.1.2

Thank you for any guidance and help in advance,
Jack

It is a network misconfiguration, you need to more fully explain where “everything is” and on what network all your devices are network wise to get a better read here.

Did you configure your localnet in SIP settings.

How do the remote phones get to Asterisk (VPN?) that address needs to be defined as a localnet also.

Thanks! I fixed a type I had on the localnet and now have good internal phone calls. External calls have a 1-way audio issue. But this is progress. I will add another post if I cannot get this resolved.

Thank you again

I had the same issue, server and softphones were on the same subnet, no workstation firewalls etc… The problem I found was not an issue with the network but with the version of softphone I had installed. I was using Zoiper for Mac and iPhone/iPod. Once I installed Xlite everything worked fine.

Just my 2¢

I created an extension and configured Zoiper on my android phone. It can receive call from desktop Zoiper but cannot make call to it. Please see the log

[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:21] Set(“SIP/1002-00000005”, “__CALLFILENAME=out-08030722697-1002-20131025-090733-1382695653.5”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:22] Goto(“SIP/1002-00000005”, “out,1”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Goto (sub-record-check,out,1)
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000005”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/1002-00000005”, “0?record,1(exten,08030722697,1002)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:3] Return(“SIP/1002-00000005”, “”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:5] Macro(“SIP/1002-00000005”, “dialout-trunk,2,08030722697,off”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000005”, “DIAL_TRUNK=2”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/1002-00000005”, “0?sub-pincheck,s,1()”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000005”, “0?disabletrunk,1”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000005”, “DIAL_NUMBER=08030722697”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000005”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000005”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/1002-00000005”, “1?nomax”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000005”, “0?skipoutcid”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000005”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:11] Macro(“SIP/1002-00000005”, “outbound-callerid,2”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000005”, “0?Set(CALLERPRES()=)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/1002-00000005”, “0?Set(REALCALLERIDNUM=1002)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000005”, “1?normcid”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000005”, “USEROUTCID=”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000005”, “EMERGENCYCID=”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000005”, “TRUNKOUTCID=2348164753013”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000005”, “1?trunkcid”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/1002-00000005”, “1?Set(CALLERID(all)=2348164753013)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/1002-00000005”, “0?Set(CALLERID(all)=)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/1002-00000005”, “0?Set(CALLERID(all)=)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/1002-00000005”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:18] Set(“SIP/1002-00000005”, “CDR(outbound_cnum)=2348164753013”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:19] Set(“SIP/1002-00000005”, “CDR(outbound_cnam)=”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/1002-00000005”, “0?sub-flp-2,s,1()”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:13] Set(“SIP/1002-00000005”, “OUTNUM=08030722697”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:14] Set(“SIP/1002-00000005”, “custom=DAHDI/G0”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/1002-00000005”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/1002-00000005”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:17] Macro(“SIP/1002-00000005”, “dialout-trunk-predial-hook,”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] MacroExit(“SIP/1002-00000005”, “”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/1002-00000005”, “0?bypass,1”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/1002-00000005”, “1?Set(CONNECTEDLINE(num,i)=08030722697)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/1002-00000005”, “1?Set(CONNECTEDLINE(name,i)=CID:2348164753013)”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/1002-00000005”, “0?customtrunk”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:22] Dial(“SIP/1002-00000005”, “DAHDI/G0/08030722697,300,Tt”) in new stack
[2013-10-25 09:07:33] VERBOSE[2393][C-00000005] app_dial.c: – Called DAHDI/G0/08030722697
[2013-10-25 09:07:37] VERBOSE[2393][C-00000005] app_dial.c: – DAHDI/4-1 answered SIP/1002-00000005
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] Macro(“SIP/1002-00000005”, “hangupcall,”) in new stack
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000005”, “1?theend”) in new stack
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/1002-00000005”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/1002-00000005”, “”) in new stack
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1002-00000005’ in macro ‘hangupcall’
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/1002-00000005’
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] sig_analog.c: – Hanging up on ‘DAHDI/4-1’
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] chan_dahdi.c: – Hungup ‘DAHDI/4-1’
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/1002-00000005’ in macro ‘dialout-trunk’
[2013-10-25 09:07:58] VERBOSE[2393][C-00000005] pbx.c: == Spawn extension (from-internal, 08030722697, 5) exited non-zero on ‘SIP/1002-00000005’
[2013-10-25 09:08:37] VERBOSE[1922][C-00000006] netsock2.c: == Using SIP RTP TOS bits 184
[2013-10-25 09:08:37] VERBOSE[1922][C-00000006] netsock2.c: == Using SIP RTP CoS mark 5
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] Macro(“SIP/1002-00000006”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000006”, “TOUCH_MONITOR=1382695717.7”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000006”, “AMPUSER=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000006”, “0?report”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/1002-00000006”, “1?Set(REALCALLERIDNUM=1002)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000006”, “AMPUSER=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000006”, “AMPUSERCIDNAME=Zak”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/1002-00000006”, “0?report”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000006”, “AMPUSERCID=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000006”, “__DIAL_OPTIONS=Ttr”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000006”, “CALLERID(all)=“Zak” <1002>”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000006”, “0?limit”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000006”, “1?Set(GROUP(concurrency_limit)=1002)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/1002-00000006”, “7?sub-ccss,s,1(from-internal,08030722697)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000006”, “0?Return()”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000006”, “CCSS_SETUP=TRUE”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/1002-00000006”, “0?monitor_config,1(from-internal,08030722697):monitor_default,1(from-internal,08030722697)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000006”, “0?is_exten”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] StackPop(“SIP/1002-00000006”, “”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] Return(“SIP/1002-00000006”, “FALSE”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]-user-callerid:14] ExecIf(“SIP/1002-00000006”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/1002-00000006”, “1?continue”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:28] Set(“SIP/1002-00000006”, “CALLERID(number)=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:29] Set(“SIP/1002-00000006”, “CALLERID(name)=Zak”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:30] Set(“SIP/1002-00000006”, “CDR(cnum)=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:31] Set(“SIP/1002-00000006”, “CDR(cnam)=Zak”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:32] Set(“SIP/1002-00000006”, “CHANNEL(language)=en”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000006”, “MOHCLASS=default”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000006”, “_NODEST=”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:4] Gosub(“SIP/1002-00000006”, “sub-record-check,s,1(out,08030722697,)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000006”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/1002-00000006”, “1?check”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (sub-record-check,s,7)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000006”, “__MON_FMT=wav”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/1002-00000006”, “1?next”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (sub-record-check,s,11)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/1002-00000006”, “0?Return()”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000006”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/1002-00000006”, “0?out,1”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:14] Set(“SIP/1002-00000006”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:15] Set(“SIP/1002-00000006”, “NOW=1382695717”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:16] Set(“SIP/1002-00000006”, “__DAY=25”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:17] Set(“SIP/1002-00000006”, “__MONTH=10”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:18] Set(“SIP/1002-00000006”, “__YEAR=2013”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:19] Set(“SIP/1002-00000006”, “__TIMESTR=20131025-090837”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:20] Set(“SIP/1002-00000006”, “__FROMEXTEN=1002”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:21] Set(“SIP/1002-00000006”, “__CALLFILENAME=out-08030722697-1002-20131025-090837-1382695717.7”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:22] Goto(“SIP/1002-00000006”, “out,1”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (sub-record-check,out,1)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000006”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/1002-00000006”, “0?record,1(exten,08030722697,1002)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] Return(“SIP/1002-00000006”, “”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:5] Macro(“SIP/1002-00000006”, “dialout-trunk,2,08030722697,off”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000006”, “DIAL_TRUNK=2”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/1002-00000006”, “0?sub-pincheck,s,1()”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000006”, “0?disabletrunk,1”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000006”, “DIAL_NUMBER=08030722697”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000006”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000006”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/1002-00000006”, “1?nomax”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000006”, “0?skipoutcid”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000006”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:11] Macro(“SIP/1002-00000006”, “outbound-callerid,2”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000006”, “0?Set(CALLERPRES()=)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/1002-00000006”, “0?Set(REALCALLERIDNUM=1002)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000006”, “1?normcid”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000006”, “USEROUTCID=”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000006”, “EMERGENCYCID=”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000006”, “TRUNKOUTCID=2348164753013”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000006”, “1?trunkcid”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/1002-00000006”, “1?Set(CALLERID(all)=2348164753013)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/1002-00000006”, “0?Set(CALLERID(all)=)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/1002-00000006”, “0?Set(CALLERID(all)=)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/1002-00000006”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:18] Set(“SIP/1002-00000006”, “CDR(outbound_cnum)=2348164753013”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:19] Set(“SIP/1002-00000006”, “CDR(outbound_cnam)=”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/1002-00000006”, “0?sub-flp-2,s,1()”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:13] Set(“SIP/1002-00000006”, “OUTNUM=08030722697”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:14] Set(“SIP/1002-00000006”, “custom=DAHDI/G0”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/1002-00000006”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/1002-00000006”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:17] Macro(“SIP/1002-00000006”, “dialout-trunk-predial-hook,”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] MacroExit(“SIP/1002-00000006”, “”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/1002-00000006”, “0?bypass,1”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/1002-00000006”, “1?Set(CONNECTEDLINE(num,i)=08030722697)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/1002-00000006”, “1?Set(CONNECTEDLINE(name,i)=CID:2348164753013)”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/1002-00000006”, “0?customtrunk”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:22] Dial(“SIP/1002-00000006”, “DAHDI/G0/08030722697,300,Tt”) in new stack
[2013-10-25 09:08:37] VERBOSE[2395][C-00000006] app_dial.c: – Called DAHDI/G0/08030722697
[2013-10-25 09:08:41] VERBOSE[2395][C-00000006] app_dial.c: – DAHDI/4-1 answered SIP/1002-00000006
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] Macro(“SIP/1002-00000006”, “hangupcall,”) in new stack
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000006”, “1?theend”) in new stack
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/1002-00000006”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/1002-00000006”, “”) in new stack
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1002-00000006’ in macro ‘hangupcall’
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/1002-00000006’
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] sig_analog.c: – Hanging up on ‘DAHDI/4-1’
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] chan_dahdi.c: – Hungup ‘DAHDI/4-1’
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/1002-00000006’ in macro ‘dialout-trunk’
[2013-10-25 09:08:46] VERBOSE[2395][C-00000006] pbx.c: == Spawn extension (from-internal, 08030722697, 5) exited non-zero on ‘SIP/1002-00000006’
[2013-10-25 09:09:03] VERBOSE[1922][C-00000007] netsock2.c: == Using SIP RTP TOS bits 184
[2013-10-25 09:09:03] VERBOSE[1922][C-00000007] netsock2.c: == Using SIP RTP CoS mark 5
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “__RINGTIMER=15”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Macro(“SIP/1002-00000007”, “exten-vm,novm,1000,0,0,0”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Macro(“SIP/1002-00000007”, “user-callerid,”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “TOUCH_MONITOR=1382695743.9”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “AMPUSER=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000007”, “0?report”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/1002-00000007”, “1?Set(REALCALLERIDNUM=1002)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “AMPUSER=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000007”, “AMPUSERCIDNAME=Zak”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/1002-00000007”, “0?report”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000007”, “AMPUSERCID=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000007”, “__DIAL_OPTIONS=Ttr”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000007”, “CALLERID(all)=“Zak” <1002>”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000007”, “0?limit”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000007”, “0?Set(GROUP(concurrency_limit)=1002)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/1002-00000007”, “7?sub-ccss,s,1(macro-exten-vm,1000)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000007”, “0?Return()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “CCSS_SETUP=TRUE”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/1002-00000007”, “0?monitor_config,1(macro-exten-vm,1000):monitor_default,1(macro-exten-vm,1000)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000007”, “1?is_exten”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (sub-ccss,monitor_default,4)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] Return(“SIP/1002-00000007”, “TRUE”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/1002-00000007”, “7?agent_config,1():agent_default,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000007”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000007”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2013-10-25 09:09:03] WARNING[2396][C-00000007] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000007”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/1002-00000007”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/1002-00000007”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000007”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2013-10-25 09:09:03] WARNING[2396][C-00000007] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “DB(AMPUSER/1002/ccss/last_number)=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/1002-00000007”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/1002-00000007”, “0?continue”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:16] Set(“SIP/1002-00000007”, “__TTL=64”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:17] GotoIf(“SIP/1002-00000007”, “1?continue”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:28] Set(“SIP/1002-00000007”, “CALLERID(number)=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:29] Set(“SIP/1002-00000007”, “CALLERID(name)=Zak”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:30] Set(“SIP/1002-00000007”, “CDR(cnum)=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:31] Set(“SIP/1002-00000007”, “CDR(cnam)=Zak”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:32] Set(“SIP/1002-00000007”, “CHANNEL(language)=en”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “RingGroupMethod=none”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000007”, “__EXTTOCALL=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000007”, “__PICKUPMARK=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “RT=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000007”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/1002-00000007”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] Gosub(“SIP/1002-00000007”, “sub-record-check,s,1(exten,1000,)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/1002-00000007”, “1?check”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (sub-record-check,s,7)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000007”, “__MON_FMT=wav”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/1002-00000007”, “1?next”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (sub-record-check,s,11)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/1002-00000007”, “0?Return()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000007”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/1002-00000007”, “0?exten,1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:14] Set(“SIP/1002-00000007”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:15] Set(“SIP/1002-00000007”, “NOW=1382695743”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:16] Set(“SIP/1002-00000007”, “__DAY=25”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:17] Set(“SIP/1002-00000007”, “__MONTH=10”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:18] Set(“SIP/1002-00000007”, “__YEAR=2013”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:19] Set(“SIP/1002-00000007”, “__TIMESTR=20131025-090903”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:20] Set(“SIP/1002-00000007”, “__FROMEXTEN=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:21] Set(“SIP/1002-00000007”, “__CALLFILENAME=exten-1000-1002-20131025-090903-1382695743.9”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:22] Goto(“SIP/1002-00000007”, “exten,1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (sub-record-check,exten,1)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000007”, “0?callee”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “__REC_POLICY_MODE=dontcare”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000007”, “1?caller”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (sub-record-check,exten,10)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000007”, “__REC_POLICY_MODE=dontcare”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/1002-00000007”, “0?record,1(exten,1000,1002)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:12] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000007”, “1?macrodial”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-exten-vm,s,15)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:15] GosubIf(“SIP/1002-00000007”, “0?clrheader,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:16] Macro(“SIP/1002-00000007”, “dial-one,Ttr,1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “DEXTEN=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “DIALSTATUS_CW=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/1002-00000007”, “0?screen,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/1002-00000007”, “0?cf,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/1002-00000007”, “1?skip1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-dial-one,s,8)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/1002-00000007”, “0?nodial”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000007”, “0?continue”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000007”, “EXTHASCW=ENABLED”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000007”, “0?next1:cwinusebusy”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-dial-one,s,23)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/1002-00000007”, “1?next3:continue”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-dial-one,s,24)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/1002-00000007”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/1002-00000007”, “0?nodial”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/1002-00000007”, “1?dstring,1():dlocal,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “DSTRING=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “DEVICES=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/1002-00000007”, “0?Return()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/1002-00000007”, “0?Set(DEVICES=000)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “LOOPCNT=1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000007”, “ITER=1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000007”, “THISDIAL=SIP/1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/1002-00000007”, “1?zap2dahdi,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000007”, “0?Return()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000007”, “NEWDIAL=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000007”, “LOOPCNT2=1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000007”, “ITER2=1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000007”, “THISPART2=SIP/1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000007”, “0?Set(THISPART2=DAHDI/1000)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000007”, “NEWDIAL=SIP/1000&”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000007”, “ITER2=2”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000007”, “0?begin2”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000007”, “THISDIAL=SIP/1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000007”, “DSTRING=SIP/1000&”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000007”, “ITER=2”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000007”, “0?begin”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:12] Set(“SIP/1002-00000007”, “DSTRING=SIP/1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:13] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/1002-00000007”, “0?nodial”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/1002-00000007”, “0?skiptrace”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:29] GosubIf(“SIP/1002-00000007”, “1?ctset,1():ctclear,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000007”, “DB(CALLTRACE/1000)=1002”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] Return(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:30] Set(“SIP/1002-00000007”, “D_OPTIONS=Ttr”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/1002-00000007”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/1002-00000007”, “0?SIPAddHeader()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/1002-00000007”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/1002-00000007”, “0?qwait,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:35] Set(“SIP/1002-00000007”, “__CWIGNORE=”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:36] Set(“SIP/1002-00000007”, “__KEEPCID=TRUE”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/1002-00000007”, “0?usegoto,1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/1002-00000007”, “0?godial”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:39] Set(“SIP/1002-00000007”, “CONNECTEDLINE(name,i)=Operator”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:40] Set(“SIP/1002-00000007”, “CONNECTEDLINE(num)=1000”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:41] Set(“SIP/1002-00000007”, “D_OPTIONS=TtrI”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:42] Dial(“SIP/1002-00000007”, “SIP/1000,TtrI”) in new stack
[2013-10-25 09:09:03] WARNING[2396][C-00000007] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:43] ExecIf(“SIP/1002-00000007”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:44] ExecIf(“SIP/1002-00000007”, “0?Set(DIALSTATUS=)”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:45] GosubIf(“SIP/1002-00000007”, “0?s-CHANUNAVAIL,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:46] MacroExit(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:17] Set(“SIP/1002-00000007”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:18] GosubIf(“SIP/1002-00000007”, “0?docfu,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:19] GosubIf(“SIP/1002-00000007”, “0?docfb,1()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:20] Set(“SIP/1002-00000007”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:21] ExecIf(“SIP/1002-00000007”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:22] GotoIf(“SIP/1002-00000007”, “1?s-CHANUNAVAIL,1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000007”, “0?exit,1”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:2] PlayTones(“SIP/1002-00000007”, “congestion”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:3] Congestion(“SIP/1002-00000007”, “10”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] app_macro.c: == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/1002-00000007’ in macro ‘exten-vm’
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: == Spawn extension (from-internal, 1000, 2) exited non-zero on ‘SIP/1002-00000007’
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/1002-00000007”, “”) in new stack
[2013-10-25 09:09:03] VERBOSE[2396][C-00000007] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1002-00000007’
[2013-10-25 09:09:50] VERBOSE[1922][C-00000008] netsock2.c: == Using SIP RTP TOS bits 184
[2013-10-25 09:09:50] VERBOSE[1922][C-00000008] netsock2.c: == Using SIP RTP CoS mark 5
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “__RINGTIMER=15”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Macro(“SIP/1002-00000008”, “exten-vm,novm,1000,0,0,0”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Macro(“SIP/1002-00000008”, “user-callerid,”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “TOUCH_MONITOR=1382695790.10”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “AMPUSER=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000008”, “0?report”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/1002-00000008”, “1?Set(REALCALLERIDNUM=1002)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “AMPUSER=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000008”, “AMPUSERCIDNAME=Zak”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/1002-00000008”, “0?report”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000008”, “AMPUSERCID=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000008”, “__DIAL_OPTIONS=Ttr”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000008”, “CALLERID(all)=“Zak” <1002>”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000008”, “0?limit”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000008”, “0?Set(GROUP(concurrency_limit)=1002)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/1002-00000008”, “7?sub-ccss,s,1(macro-exten-vm,1000)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000008”, “0?Return()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “CCSS_SETUP=TRUE”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/1002-00000008”, “0?monitor_config,1(macro-exten-vm,1000):monitor_default,1(macro-exten-vm,1000)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000008”, “1?is_exten”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (sub-ccss,monitor_default,4)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] Return(“SIP/1002-00000008”, “TRUE”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/1002-00000008”, “7?agent_config,1():agent_default,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000008”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000008”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2013-10-25 09:09:50] WARNING[2397][C-00000008] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000008”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/1002-00000008”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/1002-00000008”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000008”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2013-10-25 09:09:50] WARNING[2397][C-00000008] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “DB(AMPUSER/1002/ccss/last_number)=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/1002-00000008”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/1002-00000008”, “0?continue”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:16] Set(“SIP/1002-00000008”, “__TTL=64”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:17] GotoIf(“SIP/1002-00000008”, “1?continue”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:28] Set(“SIP/1002-00000008”, “CALLERID(number)=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:29] Set(“SIP/1002-00000008”, “CALLERID(name)=Zak”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:30] Set(“SIP/1002-00000008”, “CDR(cnum)=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:31] Set(“SIP/1002-00000008”, “CDR(cnam)=Zak”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:32] Set(“SIP/1002-00000008”, “CHANNEL(language)=en”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “RingGroupMethod=none”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000008”, “__EXTTOCALL=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000008”, “__PICKUPMARK=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “RT=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000008”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/1002-00000008”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] Gosub(“SIP/1002-00000008”, “sub-record-check,s,1(exten,1000,)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/1002-00000008”, “1?check”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (sub-record-check,s,7)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000008”, “__MON_FMT=wav”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/1002-00000008”, “1?next”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (sub-record-check,s,11)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/1002-00000008”, “0?Return()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/1002-00000008”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/1002-00000008”, “0?exten,1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:14] Set(“SIP/1002-00000008”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:15] Set(“SIP/1002-00000008”, “NOW=1382695790”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:16] Set(“SIP/1002-00000008”, “__DAY=25”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:17] Set(“SIP/1002-00000008”, “__MONTH=10”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:18] Set(“SIP/1002-00000008”, “__YEAR=2013”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:19] Set(“SIP/1002-00000008”, “__TIMESTR=20131025-090950”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:20] Set(“SIP/1002-00000008”, “__FROMEXTEN=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:21] Set(“SIP/1002-00000008”, “__CALLFILENAME=exten-1000-1002-20131025-090950-1382695790.10”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:22] Goto(“SIP/1002-00000008”, “exten,1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (sub-record-check,exten,1)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000008”, “0?callee”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “__REC_POLICY_MODE=dontcare”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/1002-00000008”, “1?caller”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (sub-record-check,exten,10)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000008”, “__REC_POLICY_MODE=dontcare”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/1002-00000008”, “0?record,1(exten,1000,1002)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:12] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000008”, “1?macrodial”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-exten-vm,s,15)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:15] GosubIf(“SIP/1002-00000008”, “0?clrheader,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:16] Macro(“SIP/1002-00000008”, “dial-one,Ttr,1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “DEXTEN=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “DIALSTATUS_CW=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/1002-00000008”, “0?screen,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/1002-00000008”, “0?cf,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/1002-00000008”, “1?skip1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-dial-one,s,8)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/1002-00000008”, “0?nodial”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000008”, “0?continue”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000008”, “EXTHASCW=ENABLED”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000008”, “0?next1:cwinusebusy”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-dial-one,s,23)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/1002-00000008”, “1?next3:continue”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-dial-one,s,24)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/1002-00000008”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/1002-00000008”, “0?nodial”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/1002-00000008”, “1?dstring,1():dlocal,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “DSTRING=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “DEVICES=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/1002-00000008”, “0?Return()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/1002-00000008”, “0?Set(DEVICES=000)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “LOOPCNT=1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] Set(“SIP/1002-00000008”, “ITER=1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000008”, “THISDIAL=SIP/1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/1002-00000008”, “1?zap2dahdi,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/1002-00000008”, “0?Return()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Set(“SIP/1002-00000008”, “NEWDIAL=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] Set(“SIP/1002-00000008”, “LOOPCNT2=1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:4] Set(“SIP/1002-00000008”, “ITER2=1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:5] Set(“SIP/1002-00000008”, “THISPART2=SIP/1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/1002-00000008”, “0?Set(THISPART2=DAHDI/1000)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:7] Set(“SIP/1002-00000008”, “NEWDIAL=SIP/1000&”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:8] Set(“SIP/1002-00000008”, “ITER2=2”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/1002-00000008”, “0?begin2”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000008”, “THISDIAL=SIP/1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:9] Set(“SIP/1002-00000008”, “DSTRING=SIP/1000&”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:10] Set(“SIP/1002-00000008”, “ITER=2”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/1002-00000008”, “0?begin”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:12] Set(“SIP/1002-00000008”, “DSTRING=SIP/1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:13] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/1002-00000008”, “0?nodial”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/1002-00000008”, “0?skiptrace”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:29] GosubIf(“SIP/1002-00000008”, “1?ctset,1():ctclear,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Set(“SIP/1002-00000008”, “DB(CALLTRACE/1000)=1002”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] Return(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:30] Set(“SIP/1002-00000008”, “D_OPTIONS=Ttr”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/1002-00000008”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/1002-00000008”, “0?SIPAddHeader()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/1002-00000008”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/1002-00000008”, “0?qwait,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:35] Set(“SIP/1002-00000008”, “__CWIGNORE=”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:36] Set(“SIP/1002-00000008”, “__KEEPCID=TRUE”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/1002-00000008”, “0?usegoto,1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/1002-00000008”, “0?godial”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:39] Set(“SIP/1002-00000008”, “CONNECTEDLINE(name,i)=Operator”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:40] Set(“SIP/1002-00000008”, “CONNECTEDLINE(num)=1000”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:41] Set(“SIP/1002-00000008”, “D_OPTIONS=TtrI”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:42] Dial(“SIP/1002-00000008”, “SIP/1000,TtrI”) in new stack
[2013-10-25 09:09:50] WARNING[2397][C-00000008] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:43] ExecIf(“SIP/1002-00000008”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:44] ExecIf(“SIP/1002-00000008”, “0?Set(DIALSTATUS=)”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:45] GosubIf(“SIP/1002-00000008”, “0?s-CHANUNAVAIL,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:46] MacroExit(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:17] Set(“SIP/1002-00000008”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:18] GosubIf(“SIP/1002-00000008”, “0?docfu,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:19] GosubIf(“SIP/1002-00000008”, “0?docfb,1()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:20] Set(“SIP/1002-00000008”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:21] ExecIf(“SIP/1002-00000008”, “0?MacroExit()”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:22] GotoIf(“SIP/1002-00000008”, “1?s-CHANUNAVAIL,1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/1002-00000008”, “0?exit,1”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:2] PlayTones(“SIP/1002-00000008”, “congestion”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:3] Congestion(“SIP/1002-00000008”, “10”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] app_macro.c: == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/1002-00000008’ in macro ‘exten-vm’
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: == Spawn extension (from-internal, 1000, 2) exited non-zero on ‘SIP/1002-00000008’
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/1002-00000008”, “”) in new stack
[2013-10-25 09:09:50] VERBOSE[2397][C-00000008] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1002-00000008’
[2013-10-25 09:11:00] NOTICE[1922] chan_sip.c: Peer ‘1002’ is now UNREACHABLE! Last qualify: 3
[2013-10-25 09:11:50] VERBOSE[1922] chan_sip.c: – Registered SIP ‘1002’ at 192.168.0.108:33010
[2013-10-25 09:11:50] NOTICE[1922] chan_sip.c: Peer ‘1002’ is now Reachable. (59ms / 2000ms)

Looks like Peer 1002 is unavailable, post output of Asterisk command ‘sip show peers’