Incoming Calls - "Not accepting call completion offers"

I’m running the new Incredible PBX in a VMware client. I have the ports forwarded, but for some reason incoming calls go to voicemail without ringing the phone. I had it working with the same settings on a dedicated box, so i’m wondering what is going on.

It’s plugged in to my Linksys SPA-2100, and outbound is working fine. Because the call does go to voicemail, that seems to indicate it’s not a network problem. I must have something set incorrectly, but I am at a loss for what that could possibly be.

When I call, it will ring, and then after a few seconds the ring will go kinda crazy and stop having the gap in between the tones (riiiiiing…pause…riiiiiiiiing). The pause is about 1/4 second. Here’s the log output, if anyone has any suggestions i’d really appreciate it:

[2010-11-11 16:16:04] VERBOSE[7099] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:04] NOTICE[7099] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000c1
[2010-11-11 16:16:04] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be redirecting info has changed, passing it to SIP/192.168.1.122-000000bc
[2010-11-11 16:16:04] VERBOSE[3555] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:04] VERBOSE[3555] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:04] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb redirecting info has changed, passing it to SIP/192.168.1.122-000000b9
[2010-11-11 16:16:04] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 redirecting info has changed, passing it to SIP/192.168.1.122-000000b6
[2010-11-11 16:16:04] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 redirecting info has changed, passing it to Gtalk/+1**********-f0b5
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c2", "Received incoming SIP connection from unknown peer to 501") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c2", "DID=501") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:3] Goto("SIP/192.168.1.122-000000c2", "s,1") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Goto (from-sip-external,s,1)
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c2", "1?from-trunk,501,1") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Goto (from-trunk,501,1)
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c2", "Catch-All DID Match - Found 501 - You probably want a DID for this.") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] Goto("SIP/192.168.1.122-000000c2", "ext-did,s,1") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Goto (ext-did,s,1)
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c2", "__FROM_DID=s") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/192.168.1.122-000000c2", "cidlookup,cidlookup_3,1") in new stack
[2010-11-11 16:16:04] VERBOSE[7106] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:06] VERBOSE[7106] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c2", "CALLERID(name)=ORLANDO, FL") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] Return("SIP/192.168.1.122-000000c2", "") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:3] Gosub("SIP/192.168.1.122-000000c2", "app-blacklist-check,s,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c2", "0?blacklisted") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c2", "CALLED_BLACKLIST=1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:3] Return("SIP/192.168.1.122-000000c2", "") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c2", "0 ?Set(CALLERID(name)=**********)") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c2", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c2", "CALLERPRES()=allowed_not_screened") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:7] Goto("SIP/192.168.1.122-000000c2", "ext-group,777,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (ext-group,777,1)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] Macro("SIP/192.168.1.122-000000c2", "user-callerid,") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c2", "AMPUSER=**********") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c2", "0?report") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/192.168.1.122-000000c2", "1?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c2", "AMPUSER=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c2", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/192.168.1.122-000000c2", "1?report") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-user-callerid,s,10)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:10] GotoIf("SIP/192.168.1.122-000000c2", "0?continue") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c2", "__TTL=64") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/192.168.1.122-000000c2", "1?continue") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-user-callerid,s,19)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:19] NoOp("SIP/192.168.1.122-000000c2", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c2", "1?skipdb") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (ext-group,777,4)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c2", "__NODEST=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c2", "__BLKVM_OVERRIDE=BLKVM/777/SIP/192.168.1.122-000000c2") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c2", "__BLKVM_BASE=777") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c2", "DB(BLKVM/777/SIP/192.168.1.122-000000c2)=TRUE") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:8] Set("SIP/192.168.1.122-000000c2", "RRNODEST=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:9] Set("SIP/192.168.1.122-000000c2", "__NODEST=777") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:10] GosubIf("SIP/192.168.1.122-000000c2", "0?sub-rgsetcid,s,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c2", "RecordMethod=Group") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:12] Macro("SIP/192.168.1.122-000000c2", "record-enable,701,Group") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c2", "1?check") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-record-enable,s,4)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c2", "0?MacroExit()") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/192.168.1.122-000000c2", "1?Group:OUT") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-record-enable,s,6)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c2", "LOOPCNT=1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c2", "ITER=1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/192.168.1.122-000000c2", "1?continue") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-record-enable,s,13)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c2", "ITER=2") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:14] GotoIf("SIP/192.168.1.122-000000c2", "0?begin") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/192.168.1.122-000000c2", "0?IN") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/192.168.1.122-000000c2", "1?MacroExit()") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c2", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:14] Macro("SIP/192.168.1.122-000000c2", "dial,20,tr,701") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c2", "1?dial") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Goto (macro-dial,s,3)
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:3] AGI("SIP/192.168.1.122-000000c2", "dialparties.agi") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: dialparties.agi: Caller ID name is 'ORLANDO, FL' number is '**********'
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- dialparties.agi: Added extension 701 to extension map
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- dialparties.agi: Extension 701 cf is disabled
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- dialparties.agi: Extension 701 do not disturb is disabled
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- dialparties.agi: dbset CALLTRACE/701 to **********
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- dialparties.agi: Filtered ARG3: 701
[2010-11-11 16:16:06] VERBOSE[7104] res_agi.c: -- <SIP/192.168.1.122-000000c2>AGI Script dialparties.agi completed, returning 0
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [[email protected]:7] Dial("SIP/192.168.1.122-000000c2", "SIP/701,20,trM(auto-blkvm)") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:06] VERBOSE[7104] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:06] VERBOSE[7104] app_dial.c: -- Called 701
[2010-11-11 16:16:06] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 is ringing
[2010-11-11 16:16:06] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be is ringing
[2010-11-11 16:16:06] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb is ringing
[2010-11-11 16:16:06] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 is ringing
[2010-11-11 16:16:06] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 is ringing
[2010-11-11 16:16:06] VERBOSE[3555] chan_sip.c: -- Got SIP response 302 "Moved Temporarily" back from 192.168.1.123:5060
[2010-11-11 16:16:06] VERBOSE[7104] app_dial.c: -- Now forwarding SIP/192.168.1.122-000000c2 to 'SIP/501::::[email protected]' (thanks to SIP/701-000000c3)
[2010-11-11 16:16:06] VERBOSE[7104] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:06] VERBOSE[7104] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:06] NOTICE[7104] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000c4
[2010-11-11 16:16:06] VERBOSE[3555] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:06] VERBOSE[3555] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:06] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 redirecting info has changed, passing it to SIP/192.168.1.122-000000bf
[2010-11-11 16:16:06] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be redirecting info has changed, passing it to SIP/192.168.1.122-000000bc
[2010-11-11 16:16:06] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb redirecting info has changed, passing it to SIP/192.168.1.122-000000b9
[2010-11-11 16:16:06] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 redirecting info has changed, passing it to SIP/192.168.1.122-000000b6
[2010-11-11 16:16:06] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 redirecting info has changed, passing it to Gtalk/+1**********-f0b5
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c5", "Received incoming SIP connection from unknown peer to 501") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c5", "DID=501") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:3] Goto("SIP/192.168.1.122-000000c5", "s,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Goto (from-sip-external,s,1)
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c5", "1?from-trunk,501,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Goto (from-trunk,501,1)
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c5", "Catch-All DID Match - Found 501 - You probably want a DID for this.") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] Goto("SIP/192.168.1.122-000000c5", "ext-did,s,1") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Goto (ext-did,s,1)
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c5", "__FROM_DID=s") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/192.168.1.122-000000c5", "cidlookup,cidlookup_3,1") in new stack
[2010-11-11 16:16:07] VERBOSE[7111] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:09] VERBOSE[7111] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c5", "CALLERID(name)=ORLANDO, FL") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] Return("SIP/192.168.1.122-000000c5", "") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:3] Gosub("SIP/192.168.1.122-000000c5", "app-blacklist-check,s,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c5", "0?blacklisted") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c5", "CALLED_BLACKLIST=1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:3] Return("SIP/192.168.1.122-000000c5", "") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c5", "0 ?Set(CALLERID(name)=**********)") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c5", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c5", "CALLERPRES()=allowed_not_screened") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:7] Goto("SIP/192.168.1.122-000000c5", "ext-group,777,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (ext-group,777,1)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] Macro("SIP/192.168.1.122-000000c5", "user-callerid,") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c5", "AMPUSER=**********") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c5", "0?report") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/192.168.1.122-000000c5", "1?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c5", "AMPUSER=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c5", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/192.168.1.122-000000c5", "1?report") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-user-callerid,s,10)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:10] GotoIf("SIP/192.168.1.122-000000c5", "0?continue") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c5", "__TTL=64") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/192.168.1.122-000000c5", "1?continue") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-user-callerid,s,19)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:19] NoOp("SIP/192.168.1.122-000000c5", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c5", "1?skipdb") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (ext-group,777,4)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c5", "__NODEST=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c5", "__BLKVM_OVERRIDE=BLKVM/777/SIP/192.168.1.122-000000c5") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c5", "__BLKVM_BASE=777") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c5", "DB(BLKVM/777/SIP/192.168.1.122-000000c5)=TRUE") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:8] Set("SIP/192.168.1.122-000000c5", "RRNODEST=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:9] Set("SIP/192.168.1.122-000000c5", "__NODEST=777") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:10] GosubIf("SIP/192.168.1.122-000000c5", "0?sub-rgsetcid,s,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c5", "RecordMethod=Group") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:12] Macro("SIP/192.168.1.122-000000c5", "record-enable,701,Group") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c5", "1?check") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-record-enable,s,4)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c5", "0?MacroExit()") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/192.168.1.122-000000c5", "1?Group:OUT") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-record-enable,s,6)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c5", "LOOPCNT=1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c5", "ITER=1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/192.168.1.122-000000c5", "1?continue") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-record-enable,s,13)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c5", "ITER=2") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:14] GotoIf("SIP/192.168.1.122-000000c5", "0?begin") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/192.168.1.122-000000c5", "0?IN") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/192.168.1.122-000000c5", "1?MacroExit()") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c5", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:14] Macro("SIP/192.168.1.122-000000c5", "dial,20,tr,701") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c5", "1?dial") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Goto (macro-dial,s,3)
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:3] AGI("SIP/192.168.1.122-000000c5", "dialparties.agi") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: dialparties.agi: Caller ID name is 'ORLANDO, FL' number is '**********'
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- dialparties.agi: Added extension 701 to extension map
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- dialparties.agi: Extension 701 cf is disabled
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- dialparties.agi: Extension 701 do not disturb is disabled
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- dialparties.agi: dbset CALLTRACE/701 to **********
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- dialparties.agi: Filtered ARG3: 701
[2010-11-11 16:16:09] VERBOSE[7109] res_agi.c: -- <SIP/192.168.1.122-000000c5>AGI Script dialparties.agi completed, returning 0
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [[email protected]:7] Dial("SIP/192.168.1.122-000000c5", "SIP/701,20,trM(auto-blkvm)") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:09] VERBOSE[7109] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:09] VERBOSE[7109] app_dial.c: -- Called 701
[2010-11-11 16:16:09] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 is ringing
[2010-11-11 16:16:09] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 is ringing
[2010-11-11 16:16:09] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be is ringing
[2010-11-11 16:16:09] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb is ringing
[2010-11-11 16:16:09] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 is ringing
[2010-11-11 16:16:09] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 is ringing
[2010-11-11 16:16:09] VERBOSE[3555] chan_sip.c: -- Got SIP response 302 "Moved Temporarily" back from 192.168.1.123:5060
[2010-11-11 16:16:09] VERBOSE[7109] app_dial.c: -- Now forwarding SIP/192.168.1.122-000000c5 to 'SIP/501::::[email protected]' (thanks to SIP/701-000000c6)
[2010-11-11 16:16:09] VERBOSE[7109] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:09] VERBOSE[7109] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:09] NOTICE[7109] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000c7
[2010-11-11 16:16:09] VERBOSE[3555] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:09] VERBOSE[3555] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:09] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 redirecting info has changed, passing it to SIP/192.168.1.122-000000c2
[2010-11-11 16:16:09] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 redirecting info has changed, passing it to SIP/192.168.1.122-000000bf
[2010-11-11 16:16:09] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be redirecting info has changed, passing it to SIP/192.168.1.122-000000bc
[2010-11-11 16:16:09] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb redirecting info has changed, passing it to SIP/192.168.1.122-000000b9
[2010-11-11 16:16:09] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 redirecting info has changed, passing it to SIP/192.168.1.122-000000b6
[2010-11-11 16:16:09] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 redirecting info has changed, passing it to Gtalk/+1**********-f0b5
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c8", "Received incoming SIP connection from unknown peer to 501") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c8", "DID=501") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:3] Goto("SIP/192.168.1.122-000000c8", "s,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Goto (from-sip-external,s,1)
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c8", "1?from-trunk,501,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Goto (from-trunk,501,1)
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000c8", "Catch-All DID Match - Found 501 - You probably want a DID for this.") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] Goto("SIP/192.168.1.122-000000c8", "ext-did,s,1") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Goto (ext-did,s,1)
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c8", "__FROM_DID=s") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/192.168.1.122-000000c8", "cidlookup,cidlookup_3,1") in new stack
[2010-11-11 16:16:10] VERBOSE[7116] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:11] VERBOSE[7116] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c8", "CALLERID(name)=ORLANDO, FL") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] Return("SIP/192.168.1.122-000000c8", "") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:3] Gosub("SIP/192.168.1.122-000000c8", "app-blacklist-check,s,1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c8", "0?blacklisted") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000c8", "CALLED_BLACKLIST=1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:3] Return("SIP/192.168.1.122-000000c8", "") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c8", "0 ?Set(CALLERID(name)=**********)") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c8", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c8", "CALLERPRES()=allowed_not_screened") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:7] Goto("SIP/192.168.1.122-000000c8", "ext-group,777,1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (ext-group,777,1)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] Macro("SIP/192.168.1.122-000000c8", "user-callerid,") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000c8", "AMPUSER=**********") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c8", "0?report") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/192.168.1.122-000000c8", "1?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c8", "AMPUSER=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c8", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/192.168.1.122-000000c8", "1?report") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-user-callerid,s,10)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:10] GotoIf("SIP/192.168.1.122-000000c8", "0?continue") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c8", "__TTL=64") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/192.168.1.122-000000c8", "1?continue") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-user-callerid,s,19)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:19] NoOp("SIP/192.168.1.122-000000c8", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000c8", "1?skipdb") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (ext-group,777,4)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000c8", "__NODEST=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000c8", "__BLKVM_OVERRIDE=BLKVM/777/SIP/192.168.1.122-000000c8") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c8", "__BLKVM_BASE=777") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c8", "DB(BLKVM/777/SIP/192.168.1.122-000000c8)=TRUE") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:8] Set("SIP/192.168.1.122-000000c8", "RRNODEST=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:9] Set("SIP/192.168.1.122-000000c8", "__NODEST=777") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:10] GosubIf("SIP/192.168.1.122-000000c8", "0?sub-rgsetcid,s,1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000c8", "RecordMethod=Group") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:12] Macro("SIP/192.168.1.122-000000c8", "record-enable,701,Group") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c8", "1?check") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-record-enable,s,4)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000c8", "0?MacroExit()") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/192.168.1.122-000000c8", "1?Group:OUT") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-record-enable,s,6)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000c8", "LOOPCNT=1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000c8", "ITER=1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/192.168.1.122-000000c8", "1?continue") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-record-enable,s,13)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c8", "ITER=2") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:14] GotoIf("SIP/192.168.1.122-000000c8", "0?begin") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/192.168.1.122-000000c8", "0?IN") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/192.168.1.122-000000c8", "1?MacroExit()") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000c8", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:14] Macro("SIP/192.168.1.122-000000c8", "dial,20,tr,701") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000c8", "1?dial") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Goto (macro-dial,s,3)
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:3] AGI("SIP/192.168.1.122-000000c8", "dialparties.agi") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: dialparties.agi: Caller ID name is 'ORLANDO, FL' number is '**********'
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- dialparties.agi: Added extension 701 to extension map
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- dialparties.agi: Extension 701 cf is disabled
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- dialparties.agi: Extension 701 do not disturb is disabled
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- dialparties.agi: dbset CALLTRACE/701 to **********
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- dialparties.agi: Filtered ARG3: 701
[2010-11-11 16:16:11] VERBOSE[7114] res_agi.c: -- <SIP/192.168.1.122-000000c8>AGI Script dialparties.agi completed, returning 0
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [[email protected]:7] Dial("SIP/192.168.1.122-000000c8", "SIP/701,20,trM(auto-blkvm)") in new stack
[2010-11-11 16:16:12] VERBOSE[7114] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:12] VERBOSE[7114] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:12] VERBOSE[7114] app_dial.c: -- Called 701
[2010-11-11 16:16:12] VERBOSE[7109] app_dial.c: -- SIP/192.168.1.122-000000c7 is ringing
[2010-11-11 16:16:12] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 is ringing
[2010-11-11 16:16:12] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 is ringing
[2010-11-11 16:16:12] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be is ringing
[2010-11-11 16:16:12] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb is ringing
[2010-11-11 16:16:12] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 is ringing
[2010-11-11 16:16:12] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 is ringing
[2010-11-11 16:16:12] VERBOSE[3555] chan_sip.c: -- Got SIP response 302 "Moved Temporarily" back from 192.168.1.123:5060
[2010-11-11 16:16:12] VERBOSE[7114] app_dial.c: -- Now forwarding SIP/192.168.1.122-000000c8 to 'SIP/501::::[email protected]122' (thanks to SIP/701-000000c9)
[2010-11-11 16:16:12] VERBOSE[7114] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:12] VERBOSE[7114] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:12] NOTICE[7114] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000ca
[2010-11-11 16:16:12] VERBOSE[7109] app_dial.c: -- SIP/192.168.1.122-000000c7 redirecting info has changed, passing it to SIP/192.168.1.122-000000c5
[2010-11-11 16:16:12] VERBOSE[3555] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:12] VERBOSE[3555] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:12] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 redirecting info has changed, passing it to SIP/192.168.1.122-000000c2
[2010-11-11 16:16:12] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 redirecting info has changed, passing it to SIP/192.168.1.122-000000bf
[2010-11-11 16:16:12] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be redirecting info has changed, passing it to SIP/192.168.1.122-000000bc
[2010-11-11 16:16:12] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb redirecting info has changed, passing it to SIP/192.168.1.122-000000b9
[2010-11-11 16:16:12] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 redirecting info has changed, passing it to SIP/192.168.1.122-000000b6
[2010-11-11 16:16:12] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 redirecting info has changed, passing it to Gtalk/+1**********-f0b5
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000cb", "Received incoming SIP connection from unknown peer to 501") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000cb", "DID=501") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:3] Goto("SIP/192.168.1.122-000000cb", "s,1") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Goto (from-sip-external,s,1)
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000cb", "1?from-trunk,501,1") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Goto (from-trunk,501,1)
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000cb", "Catch-All DID Match - Found 501 - You probably want a DID for this.") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] Goto("SIP/192.168.1.122-000000cb", "ext-did,s,1") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Goto (ext-did,s,1)
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000cb", "__FROM_DID=s") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/192.168.1.122-000000cb", "cidlookup,cidlookup_3,1") in new stack
[2010-11-11 16:16:12] VERBOSE[7121] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:14] VERBOSE[7121] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000cb", "CALLERID(name)=ORLANDO, FL") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] Return("SIP/192.168.1.122-000000cb", "") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:3] Gosub("SIP/192.168.1.122-000000cb", "app-blacklist-check,s,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000cb", "0?blacklisted") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000cb", "CALLED_BLACKLIST=1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:3] Return("SIP/192.168.1.122-000000cb", "") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000cb", "0 ?Set(CALLERID(name)=**********)") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000cb", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000cb", "CALLERPRES()=allowed_not_screened") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:7] Goto("SIP/192.168.1.122-000000cb", "ext-group,777,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (ext-group,777,1)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] Macro("SIP/192.168.1.122-000000cb", "user-callerid,") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000cb", "AMPUSER=**********") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000cb", "0?report") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/192.168.1.122-000000cb", "1?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000cb", "AMPUSER=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000cb", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/192.168.1.122-000000cb", "1?report") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-user-callerid,s,10)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:10] GotoIf("SIP/192.168.1.122-000000cb", "0?continue") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000cb", "__TTL=64") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/192.168.1.122-000000cb", "1?continue") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-user-callerid,s,19)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:19] NoOp("SIP/192.168.1.122-000000cb", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/192.168.1.122-000000cb", "1?skipdb") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (ext-group,777,4)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:4] Set("SIP/192.168.1.122-000000cb", "__NODEST=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:5] Set("SIP/192.168.1.122-000000cb", "__BLKVM_OVERRIDE=BLKVM/777/SIP/192.168.1.122-000000cb") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000cb", "__BLKVM_BASE=777") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000cb", "DB(BLKVM/777/SIP/192.168.1.122-000000cb)=TRUE") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:8] Set("SIP/192.168.1.122-000000cb", "RRNODEST=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:9] Set("SIP/192.168.1.122-000000cb", "__NODEST=777") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:10] GosubIf("SIP/192.168.1.122-000000cb", "0?sub-rgsetcid,s,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:11] Set("SIP/192.168.1.122-000000cb", "RecordMethod=Group") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:12] Macro("SIP/192.168.1.122-000000cb", "record-enable,701,Group") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000cb", "1?check") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-record-enable,s,4)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/192.168.1.122-000000cb", "0?MacroExit()") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/192.168.1.122-000000cb", "1?Group:OUT") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-record-enable,s,6)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:6] Set("SIP/192.168.1.122-000000cb", "LOOPCNT=1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:7] Set("SIP/192.168.1.122-000000cb", "ITER=1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/192.168.1.122-000000cb", "1?continue") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-record-enable,s,13)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000cb", "ITER=2") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:14] GotoIf("SIP/192.168.1.122-000000cb", "0?begin") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/192.168.1.122-000000cb", "0?IN") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:16] ExecIf("SIP/192.168.1.122-000000cb", "1?MacroExit()") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:13] Set("SIP/192.168.1.122-000000cb", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:14] Macro("SIP/192.168.1.122-000000cb", "dial,20,tr,701") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000cb", "1?dial") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Goto (macro-dial,s,3)
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:3] AGI("SIP/192.168.1.122-000000cb", "dialparties.agi") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: dialparties.agi: Caller ID name is 'ORLANDO, FL' number is '**********'
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- dialparties.agi: Added extension 701 to extension map
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- dialparties.agi: Extension 701 cf is disabled
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- dialparties.agi: Extension 701 do not disturb is disabled
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- dialparties.agi: dbset CALLTRACE/701 to **********
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- dialparties.agi: Filtered ARG3: 701
[2010-11-11 16:16:14] VERBOSE[7119] res_agi.c: -- <SIP/192.168.1.122-000000cb>AGI Script dialparties.agi completed, returning 0
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [[email protected]:7] Dial("SIP/192.168.1.122-000000cb", "SIP/701,20,trM(auto-blkvm)") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:14] VERBOSE[7119] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:14] VERBOSE[7119] app_dial.c: -- Called 701
[2010-11-11 16:16:14] VERBOSE[7114] app_dial.c: -- SIP/192.168.1.122-000000ca is ringing
[2010-11-11 16:16:14] VERBOSE[7109] app_dial.c: -- SIP/192.168.1.122-000000c7 is ringing
[2010-11-11 16:16:14] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 is ringing
[2010-11-11 16:16:14] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 is ringing
[2010-11-11 16:16:14] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be is ringing
[2010-11-11 16:16:14] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb is ringing
[2010-11-11 16:16:14] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 is ringing
[2010-11-11 16:16:14] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 is ringing
[2010-11-11 16:16:14] VERBOSE[3555] chan_sip.c: -- Got SIP response 302 "Moved Temporarily" back from 192.168.1.123:5060
[2010-11-11 16:16:14] VERBOSE[7119] app_dial.c: -- Now forwarding SIP/192.168.1.122-000000cb to 'SIP/501::::[email protected]' (thanks to SIP/701-000000cc)
[2010-11-11 16:16:14] VERBOSE[7119] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:14] VERBOSE[7119] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:14] NOTICE[7119] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000cd
[2010-11-11 16:16:14] VERBOSE[3555] netsock2.c: == Using SIP RTP TOS bits 184
[2010-11-11 16:16:14] VERBOSE[3555] netsock2.c: == Using SIP RTP CoS mark 5
[2010-11-11 16:16:14] VERBOSE[7114] app_dial.c: -- SIP/192.168.1.122-000000ca redirecting info has changed, passing it to SIP/192.168.1.122-000000c8
[2010-11-11 16:16:14] VERBOSE[7109] app_dial.c: -- SIP/192.168.1.122-000000c7 redirecting info has changed, passing it to SIP/192.168.1.122-000000c5
[2010-11-11 16:16:14] VERBOSE[7104] app_dial.c: -- SIP/192.168.1.122-000000c4 redirecting info has changed, passing it to SIP/192.168.1.122-000000c2
[2010-11-11 16:16:14] VERBOSE[7099] app_dial.c: -- SIP/192.168.1.122-000000c1 redirecting info has changed, passing it to SIP/192.168.1.122-000000bf
[2010-11-11 16:16:14] VERBOSE[7094] app_dial.c: -- SIP/192.168.1.122-000000be redirecting info has changed, passing it to SIP/192.168.1.122-000000bc
[2010-11-11 16:16:14] VERBOSE[7089] app_dial.c: -- SIP/192.168.1.122-000000bb redirecting info has changed, passing it to SIP/192.168.1.122-000000b9
[2010-11-11 16:16:14] VERBOSE[7084] app_dial.c: -- SIP/192.168.1.122-000000b8 redirecting info has changed, passing it to SIP/192.168.1.122-000000b6
[2010-11-11 16:16:14] VERBOSE[7079] app_dial.c: -- SIP/192.168.1.122-000000b5 redirecting info has changed, passing it to Gtalk/+1**********-f0b5
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000ce", "Received incoming SIP connection from unknown peer to 501") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:2] Set("SIP/192.168.1.122-000000ce", "DID=501") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:3] Goto("SIP/192.168.1.122-000000ce", "s,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Goto (from-sip-external,s,1)
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/192.168.1.122-000000ce", "1?from-trunk,501,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Goto (from-trunk,501,1)
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/192.168.1.122-000000ce", "Catch-All DID Match - Found 501 - You probably want a DID for this.") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:2] Goto("SIP/192.168.1.122-000000ce", "ext-did,s,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Goto (ext-did,s,1)
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000ce", "__FROM_DID=s") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/192.168.1.122-000000ce", "cidlookup,cidlookup_3,1") in new stack
[2010-11-11 16:16:14] VERBOSE[7126] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:15] VERBOSE[7079] app_dial.c: -- Nobody picked up in 20000 ms
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:8] Set("Gtalk/+1**********-f0b5", "DIALSTATUS=NOANSWER") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:9] GosubIf("Gtalk/+1**********-f0b5", "0?NOANSWER,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:15] Set("Gtalk/+1**********-f0b5", "RingGroupMethod=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:16] GotoIf("Gtalk/+1**********-f0b5", "0?nodest") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:17] Set("Gtalk/+1**********-f0b5", "__NODEST=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:18] NoOp("Gtalk/+1**********-f0b5", "Deleting: BLKVM/777/Gtalk/+1**********-f0b5 TRUE") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:19] Goto("Gtalk/+1**********-f0b5", "ext-local,vmu701,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (ext-local,vmu701,1)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Macro("Gtalk/+1**********-f0b5", "vm,701,NOANSWER,") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Macro("Gtalk/+1**********-f0b5", "user-callerid,SKIPTTL") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Set("Gtalk/+1**********-f0b5", "AMPUSER=**********") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:2] GotoIf("Gtalk/+1**********-f0b5", "0?report") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:3] ExecIf("Gtalk/+1**********-f0b5", "0?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:4] Set("Gtalk/+1**********-f0b5", "AMPUSER=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:5] Set("Gtalk/+1**********-f0b5", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:6] GotoIf("Gtalk/+1**********-f0b5", "1?report") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-user-callerid,s,10)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:10] GotoIf("Gtalk/+1**********-f0b5", "1?continue") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-user-callerid,s,19)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:19] NoOp("Gtalk/+1**********-f0b5", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:2] Set("Gtalk/+1**********-f0b5", "VMGAIN=""") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:3] GotoIf("Gtalk/+1**********-f0b5", "1?vmx,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-vm,vmx,1)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Set("Gtalk/+1**********-f0b5", "MEXTEN=701") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:2] Set("Gtalk/+1**********-f0b5", "MMODE=NOANSWER") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:3] Set("Gtalk/+1**********-f0b5", "RETVM=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:4] Set("Gtalk/+1**********-f0b5", "MODE=unavail") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:5] GotoIf("Gtalk/+1**********-f0b5", "1?chknomsg") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-vm,vmx,7)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:7] GotoIf("Gtalk/+1**********-f0b5", "0?s-NOANSWER,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:8] GotoIf("Gtalk/+1**********-f0b5", "1?notdirect") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-vm,vmx,10)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:10] NoOp("Gtalk/+1**********-f0b5", "Checking if ext 701 is enabled: ") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:11] GotoIf("Gtalk/+1**********-f0b5", "1?s-NOANSWER,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-vm,s-NOANSWER,1)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Macro("Gtalk/+1**********-f0b5", "get-vmcontext,701") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Set("Gtalk/+1**********-f0b5", "VMCONTEXT=default") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:2] GotoIf("Gtalk/+1**********-f0b5", "0?200:300") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Goto (macro-get-vmcontext,s,300)
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:300] NoOp("Gtalk/+1**********-f0b5", "") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [[email protected]:2] VoiceMail("Gtalk/+1**********-f0b5", "[email protected],su""") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] file.c: -- <Gtalk/+1**********-f0b5> Playing '/var/spool/asterisk/voicemail/default/701/unavail.slin' (language 'en')
[2010-11-11 16:16:15] VERBOSE[7084] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000b6' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7084] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000b6'
[2010-11-11 16:16:15] VERBOSE[7089] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000b9' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7089] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000b9'
[2010-11-11 16:16:15] VERBOSE[7094] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000bc' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7094] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000bc'
[2010-11-11 16:16:15] VERBOSE[7099] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000bf' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7099] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000bf'
[2010-11-11 16:16:15] VERBOSE[7104] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000c2' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7104] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000c2'
[2010-11-11 16:16:15] VERBOSE[7109] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000c5' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7109] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000c5'
[2010-11-11 16:16:15] VERBOSE[7114] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000c8' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7114] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000c8'
[2010-11-11 16:16:15] VERBOSE[7119] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/192.168.1.122-000000cb' in macro 'dial'
[2010-11-11 16:16:15] VERBOSE[7119] pbx.c: == Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/192.168.1.122-000000cb'
[2010-11-11 16:16:16] VERBOSE[7126] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:16] VERBOSE[7124] pbx.c: -- Executing [[email protected]:1] Set("SIP/192.168.1.122-000000ce", "CALLERID(name)=ORLANDO, FL") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] app_macro.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'Gtalk/+1**********-f0b5' in macro 'vm'
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: == Spawn extension (ext-local, vmu701, 1) exited non-zero on 'Gtalk/+1**********-f0b5'
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] Macro("Gtalk/+1**********-f0b5", "hangupcall,") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:1] GotoIf("Gtalk/+1**********-f0b5", "1?skiprg") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Goto (macro-hangupcall,s,4)
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:4] GotoIf("Gtalk/+1**********-f0b5", "0?skipblkvm") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:5] NoOp("Gtalk/+1**********-f0b5", "Cleaning Up Block VM Flag: BLKVM/777/Gtalk/+1**********-f0b5") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:6] NoOp("Gtalk/+1**********-f0b5", "Deleting: BLKVM/777/Gtalk/+1**********-f0b5 ") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:7] GotoIf("Gtalk/+1**********-f0b5", "1?theend") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Goto (macro-hangupcall,s,9)
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [[email protected]:9] Hangup("Gtalk/+1**********-f0b5", "") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Gtalk/+1**********-f0b5' in macro 'hangupcall'
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'Gtalk/+1**********-f0b5'
[2010-11-11 16:16:20] NOTICE[3555] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #9)
[2010-11-11 16:16:20] NOTICE[3555] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #3)
[2010-11-11 16:16:20] NOTICE[3555] chan_sip.c: Failed to authenticate on REGISTER to '[email protected]' (Tries 3)
[2010-11-11 16:16:21] VERBOSE[7128] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2010-11-11 16:16:22] VERBOSE[7128] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2010-11-11 16:16:23] VERBOSE[7130] manager.c: == Manager 'admin' logged on from 127.0.0.1

The only thing that seems like an error is the “NOTICE[7099] app_dial.c: Not accepting call completion offers from call-forward recipient SIP/192.168.1.122-000000c1”. Google turned up nothing helpful.

Call forward must be set on one of the vertical activation codes on the unit.

Clear all of those out and you should be all set.

In the future please do not post such long log excerpts.

If you feel the data is relevant then use pastebin.ca

Sorry about the long log, usually the [code] block makes a scrollable box. I didn’t really know what was relevant and what wasn’t.

I did try removing all vertical activation codes though, that didn’t solve the problem. I’m going to just reset the SPA-2100 to default and start over, just in case anything was changed inadvertently.