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 [501@from-sip-external: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 [501@from-sip-external:2] Set("SIP/192.168.1.122-000000c2", "DID=501") in new stack
[2010-11-11 16:16:04] VERBOSE[7104] pbx.c: -- Executing [501@from-sip-external: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 [s@from-sip-external: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 [501@from-trunk: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 [501@from-trunk: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 [s@ext-did: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 [s@ext-did: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 [cidlookup_3@cidlookup: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 [cidlookup_3@cidlookup:2] Return("SIP/192.168.1.122-000000c2", "") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@ext-did: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 [s@app-blacklist-check:1] GotoIf("SIP/192.168.1.122-000000c2", "0?blacklisted") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@app-blacklist-check: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 [s@app-blacklist-check:3] Return("SIP/192.168.1.122-000000c2", "") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [777@ext-group:1] Macro("SIP/192.168.1.122-000000c2", "user-callerid,") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/192.168.1.122-000000c2", "AMPUSER=**********") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/192.168.1.122-000000c2", "0?report") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:4] Set("SIP/192.168.1.122-000000c2", "AMPUSER=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/192.168.1.122-000000c2", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:10] GotoIf("SIP/192.168.1.122-000000c2", "0?continue") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/192.168.1.122-000000c2", "__TTL=64") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [777@ext-group: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 [777@ext-group:4] Set("SIP/192.168.1.122-000000c2", "__NODEST=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group: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 [777@ext-group: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 [777@ext-group: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 [777@ext-group:8] Set("SIP/192.168.1.122-000000c2", "RRNODEST=") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group:9] Set("SIP/192.168.1.122-000000c2", "__NODEST=777") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group: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 [777@ext-group:11] Set("SIP/192.168.1.122-000000c2", "RecordMethod=Group") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group: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 [s@macro-record-enable: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 [s@macro-record-enable:4] ExecIf("SIP/192.168.1.122-000000c2", "0?MacroExit()") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:6] Set("SIP/192.168.1.122-000000c2", "LOOPCNT=1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/192.168.1.122-000000c2", "ITER=1") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:13] Set("SIP/192.168.1.122-000000c2", "ITER=2") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/192.168.1.122-000000c2", "0?begin") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/192.168.1.122-000000c2", "0?IN") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/192.168.1.122-000000c2", "1?MacroExit()") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group:13] Set("SIP/192.168.1.122-000000c2", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:06] VERBOSE[7104] pbx.c: -- Executing [777@ext-group: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [501@from-sip-external: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 [501@from-sip-external:2] Set("SIP/192.168.1.122-000000c5", "DID=501") in new stack
[2010-11-11 16:16:06] VERBOSE[7109] pbx.c: -- Executing [501@from-sip-external: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 [s@from-sip-external: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 [501@from-trunk: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 [501@from-trunk: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 [s@ext-did: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 [s@ext-did: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 [cidlookup_3@cidlookup: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 [cidlookup_3@cidlookup:2] Return("SIP/192.168.1.122-000000c5", "") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@ext-did: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 [s@app-blacklist-check:1] GotoIf("SIP/192.168.1.122-000000c5", "0?blacklisted") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@app-blacklist-check: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 [s@app-blacklist-check:3] Return("SIP/192.168.1.122-000000c5", "") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [777@ext-group:1] Macro("SIP/192.168.1.122-000000c5", "user-callerid,") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/192.168.1.122-000000c5", "AMPUSER=**********") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/192.168.1.122-000000c5", "0?report") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:4] Set("SIP/192.168.1.122-000000c5", "AMPUSER=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/192.168.1.122-000000c5", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:10] GotoIf("SIP/192.168.1.122-000000c5", "0?continue") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/192.168.1.122-000000c5", "__TTL=64") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [777@ext-group: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 [777@ext-group:4] Set("SIP/192.168.1.122-000000c5", "__NODEST=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group: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 [777@ext-group: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 [777@ext-group: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 [777@ext-group:8] Set("SIP/192.168.1.122-000000c5", "RRNODEST=") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group:9] Set("SIP/192.168.1.122-000000c5", "__NODEST=777") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group: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 [777@ext-group:11] Set("SIP/192.168.1.122-000000c5", "RecordMethod=Group") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group: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 [s@macro-record-enable: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 [s@macro-record-enable:4] ExecIf("SIP/192.168.1.122-000000c5", "0?MacroExit()") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:6] Set("SIP/192.168.1.122-000000c5", "LOOPCNT=1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/192.168.1.122-000000c5", "ITER=1") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:13] Set("SIP/192.168.1.122-000000c5", "ITER=2") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/192.168.1.122-000000c5", "0?begin") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/192.168.1.122-000000c5", "0?IN") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/192.168.1.122-000000c5", "1?MacroExit()") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group:13] Set("SIP/192.168.1.122-000000c5", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:09] VERBOSE[7109] pbx.c: -- Executing [777@ext-group: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [501@from-sip-external: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 [501@from-sip-external:2] Set("SIP/192.168.1.122-000000c8", "DID=501") in new stack
[2010-11-11 16:16:09] VERBOSE[7114] pbx.c: -- Executing [501@from-sip-external: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 [s@from-sip-external: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 [501@from-trunk: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 [501@from-trunk: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 [s@ext-did: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 [s@ext-did: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 [cidlookup_3@cidlookup: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 [cidlookup_3@cidlookup:2] Return("SIP/192.168.1.122-000000c8", "") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@ext-did: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 [s@app-blacklist-check:1] GotoIf("SIP/192.168.1.122-000000c8", "0?blacklisted") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@app-blacklist-check: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 [s@app-blacklist-check:3] Return("SIP/192.168.1.122-000000c8", "") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [777@ext-group:1] Macro("SIP/192.168.1.122-000000c8", "user-callerid,") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/192.168.1.122-000000c8", "AMPUSER=**********") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/192.168.1.122-000000c8", "0?report") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:4] Set("SIP/192.168.1.122-000000c8", "AMPUSER=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/192.168.1.122-000000c8", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:10] GotoIf("SIP/192.168.1.122-000000c8", "0?continue") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/192.168.1.122-000000c8", "__TTL=64") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [777@ext-group: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 [777@ext-group:4] Set("SIP/192.168.1.122-000000c8", "__NODEST=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group: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 [777@ext-group: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 [777@ext-group: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 [777@ext-group:8] Set("SIP/192.168.1.122-000000c8", "RRNODEST=") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group:9] Set("SIP/192.168.1.122-000000c8", "__NODEST=777") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group: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 [777@ext-group:11] Set("SIP/192.168.1.122-000000c8", "RecordMethod=Group") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group: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 [s@macro-record-enable: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 [s@macro-record-enable:4] ExecIf("SIP/192.168.1.122-000000c8", "0?MacroExit()") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:6] Set("SIP/192.168.1.122-000000c8", "LOOPCNT=1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/192.168.1.122-000000c8", "ITER=1") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:13] Set("SIP/192.168.1.122-000000c8", "ITER=2") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/192.168.1.122-000000c8", "0?begin") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/192.168.1.122-000000c8", "0?IN") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/192.168.1.122-000000c8", "1?MacroExit()") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group:13] Set("SIP/192.168.1.122-000000c8", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:11] VERBOSE[7114] pbx.c: -- Executing [777@ext-group: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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]' (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 [501@from-sip-external: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 [501@from-sip-external:2] Set("SIP/192.168.1.122-000000cb", "DID=501") in new stack
[2010-11-11 16:16:12] VERBOSE[7119] pbx.c: -- Executing [501@from-sip-external: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 [s@from-sip-external: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 [501@from-trunk: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 [501@from-trunk: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 [s@ext-did: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 [s@ext-did: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 [cidlookup_3@cidlookup: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 [cidlookup_3@cidlookup:2] Return("SIP/192.168.1.122-000000cb", "") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@ext-did: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 [s@app-blacklist-check:1] GotoIf("SIP/192.168.1.122-000000cb", "0?blacklisted") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@app-blacklist-check: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 [s@app-blacklist-check:3] Return("SIP/192.168.1.122-000000cb", "") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [s@ext-did: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 [777@ext-group:1] Macro("SIP/192.168.1.122-000000cb", "user-callerid,") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/192.168.1.122-000000cb", "AMPUSER=**********") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/192.168.1.122-000000cb", "0?report") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:4] Set("SIP/192.168.1.122-000000cb", "AMPUSER=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/192.168.1.122-000000cb", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:10] GotoIf("SIP/192.168.1.122-000000cb", "0?continue") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/192.168.1.122-000000cb", "__TTL=64") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [777@ext-group: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 [777@ext-group:4] Set("SIP/192.168.1.122-000000cb", "__NODEST=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group: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 [777@ext-group: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 [777@ext-group: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 [777@ext-group:8] Set("SIP/192.168.1.122-000000cb", "RRNODEST=") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group:9] Set("SIP/192.168.1.122-000000cb", "__NODEST=777") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group: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 [777@ext-group:11] Set("SIP/192.168.1.122-000000cb", "RecordMethod=Group") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group: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 [s@macro-record-enable: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 [s@macro-record-enable:4] ExecIf("SIP/192.168.1.122-000000cb", "0?MacroExit()") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:6] Set("SIP/192.168.1.122-000000cb", "LOOPCNT=1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/192.168.1.122-000000cb", "ITER=1") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable: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 [s@macro-record-enable:13] Set("SIP/192.168.1.122-000000cb", "ITER=2") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/192.168.1.122-000000cb", "0?begin") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/192.168.1.122-000000cb", "0?IN") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/192.168.1.122-000000cb", "1?MacroExit()") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group:13] Set("SIP/192.168.1.122-000000cb", "RingGroupMethod=ringall") in new stack
[2010-11-11 16:16:14] VERBOSE[7119] pbx.c: -- Executing [777@ext-group: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [501@from-sip-external: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 [501@from-sip-external:2] Set("SIP/192.168.1.122-000000ce", "DID=501") in new stack
[2010-11-11 16:16:14] VERBOSE[7124] pbx.c: -- Executing [501@from-sip-external: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 [s@from-sip-external: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 [501@from-trunk: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 [501@from-trunk: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 [s@ext-did: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 [s@ext-did: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 [s@macro-dial:8] Set("Gtalk/+1**********-f0b5", "DIALSTATUS=NOANSWER") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-dial:9] GosubIf("Gtalk/+1**********-f0b5", "0?NOANSWER,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [777@ext-group:15] Set("Gtalk/+1**********-f0b5", "RingGroupMethod=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [777@ext-group:16] GotoIf("Gtalk/+1**********-f0b5", "0?nodest") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [777@ext-group:17] Set("Gtalk/+1**********-f0b5", "__NODEST=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [777@ext-group: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 [777@ext-group: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 [vmu701@ext-local:1] Macro("Gtalk/+1**********-f0b5", "vm,701,NOANSWER,") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-vm:1] Macro("Gtalk/+1**********-f0b5", "user-callerid,SKIPTTL") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Gtalk/+1**********-f0b5", "AMPUSER=**********") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Gtalk/+1**********-f0b5", "0?report") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Gtalk/+1**********-f0b5", "0?Set(REALCALLERIDNUM=**********)") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Gtalk/+1**********-f0b5", "AMPUSER=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Gtalk/+1**********-f0b5", "AMPUSERCIDNAME=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid:19] NoOp("Gtalk/+1**********-f0b5", "Using CallerID "ORLANDO, FL" <**********>") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-vm:2] Set("Gtalk/+1**********-f0b5", "VMGAIN=""") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-vm: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 [vmx@macro-vm:1] Set("Gtalk/+1**********-f0b5", "MEXTEN=701") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [vmx@macro-vm:2] Set("Gtalk/+1**********-f0b5", "MMODE=NOANSWER") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [vmx@macro-vm:3] Set("Gtalk/+1**********-f0b5", "RETVM=") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [vmx@macro-vm:4] Set("Gtalk/+1**********-f0b5", "MODE=unavail") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [vmx@macro-vm: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 [vmx@macro-vm:7] GotoIf("Gtalk/+1**********-f0b5", "0?s-NOANSWER,1") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [vmx@macro-vm: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 [vmx@macro-vm: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 [vmx@macro-vm: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 [s-NOANSWER@macro-vm:1] Macro("Gtalk/+1**********-f0b5", "get-vmcontext,701") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-get-vmcontext:1] Set("Gtalk/+1**********-f0b5", "VMCONTEXT=default") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s@macro-get-vmcontext: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 [s@macro-get-vmcontext:300] NoOp("Gtalk/+1**********-f0b5", "") in new stack
[2010-11-11 16:16:15] VERBOSE[7079] pbx.c: -- Executing [s-NOANSWER@macro-vm:2] VoiceMail("Gtalk/+1**********-f0b5", "701@default,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 [cidlookup_3@cidlookup: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 [h@ext-local:1] Macro("Gtalk/+1**********-f0b5", "hangupcall,") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [s@macro-hangupcall: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 [s@macro-hangupcall:4] GotoIf("Gtalk/+1**********-f0b5", "0?skipblkvm") in new stack
[2010-11-11 16:16:19] VERBOSE[7079] pbx.c: -- Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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.1The 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.