May 11 10:40:42 DEBUG[1316]: Setting NAT on RTP to 4 May 11 10:40:42 DEBUG[1316]: ##### Testing 192.168.10.50 with 192.168.10.0 May 11 10:40:42 DEBUG[1316]: Outgoing Call for 200 May 11 10:40:42 DEBUG[1316]: Call from user '200' is 1 out of 0 May 11 10:40:42 VERBOSE[1316]: -- Called 200 May 11 10:40:42 DEBUG[1316]: (Provisional) Stopping retransmission (but retaining packet) on '74ed5df23127558a6a40589535a5e9c8@192.16 8.10.2' Request 102: Found May 11 10:40:42 DEBUG[1316]: Acked pending invite 102 May 11 10:40:42 DEBUG[1316]: Stopping retransmission on '74ed5df23127558a6a40589535a5e9c8@192.168.10.2' of Request 102: Found May 11 10:40:42 VERBOSE[1316]: -- Got SIP response 302 "Moved Temporarily" back from 192.168.10.50 May 11 10:40:42 DEBUG[1316]: Found 302 Redirect to extension '201' May 11 10:40:42 VERBOSE[1316]: -- Now forwarding Zap/1-1 to 'Local/201@from-internal' (thanks to SIP/200-f572) May 11 10:40:42 VERBOSE[1316]: -- Executing Macro("Local/201@from-internal-654b,2", "exten-vm|201@default|201") in new stack May 11 10:40:42 VERBOSE[1316]: -- Executing SetVar("Local/201@from-internal-654b,2", "FROMCONTEXT=exten-vm") in new stack May 11 10:40:42 VERBOSE[1316]: -- Executing Macro("Local/201@from-internal-654b,2", "record-enable|201|IN") in new stack May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0 > 0?2:4") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-record-enable,s,4) May 11 10:40:42 DEBUG[1316]: Expression is '0' May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0?5:8") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-record-enable,s,8) May 11 10:40:42 DEBUG[1316]: Expression is '0' May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0?9:12") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-record-enable,s,12) May 11 10:40:42 VERBOSE[1316]: -- Executing DBget("Local/201@from-internal-654b,2", "RecEnable=RECORD-IN/201") in new stack May 11 10:40:42 VERBOSE[1316]: -- DBget: varname=RecEnable, family=RECORD-IN, key=201 May 11 10:40:42 DEBUG[1316]: Unable to find key '201' in family 'RECORD-IN' May 11 10:40:42 VERBOSE[1316]: -- DBget: Value not found in database. May 11 10:40:42 VERBOSE[1316]: -- Executing SetVar("Local/201@from-internal-654b,2", "CALLFILENAME=20060511-104042-1147369242.43" ) in new stack May 11 10:40:42 DEBUG[1316]: Expression is '0' May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0?15:99") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-record-enable,s,99) May 11 10:40:42 VERBOSE[1316]: -- Executing NoOp("Local/201@from-internal-654b,2", "NO RECORDING NEEDED") in new stack May 11 10:40:42 DEBUG[1316]: update_user_counter(200) - decrement outUse counter May 11 10:40:42 VERBOSE[1316]: -- Executing Macro("Local/201@from-internal-654b,2", "dial|30|tr|201") in new stack May 11 10:40:42 DEBUG[1316]: Expression is '0' May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0?4:2") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-dial,s,2) May 11 10:40:42 WARNING[1316]: ast_yyerror(): syntax error: syntax error; Input: != May 11 10:40:42 DEBUG[1316]: Expression is '0' May 11 10:40:42 VERBOSE[1316]: -- Executing GotoIf("Local/201@from-internal-654b,2", "0?4:3") in new stack May 11 10:40:42 VERBOSE[1316]: -- Goto (macro-dial,s,3) May 11 10:40:42 VERBOSE[1316]: -- Executing SetCIDName("Local/201@from-internal-654b,2", "BRIT. COLUMBIA ") in new stack May 11 10:40:42 VERBOSE[1316]: -- Executing AGI("Local/201@from-internal-654b,2", "dialparties.agi") in new stack May 11 10:40:42 VERBOSE[1316]: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: request = dialparties.agi May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: priority = 4 May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: extension = s May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: language = en May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: accountcode = May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: uniqueid = 1147369242.43 May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: channel = Local/201@from-internal-654b,2 May 11 10:40:44 VERBOSE[1316]: dialparties.agi: callerid = BRIT. May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: context = macro-dial May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: type = Local May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: rdnis = 200 May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: enhanced = 0.0 May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: dnid = unknown May 11 10:40:44 VERBOSE[1316]: dialparties.agi: Caller ID name is 'BRIT. COLUMBIA ' number is '604xxxxxxx' May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: Added extension 201 to extension map May 11 10:40:44 DEBUG[1316]: Unable to find key '201' in family 'CF' May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: Extension 201 cf is disabled May 11 10:40:44 DEBUG[1316]: Unable to find key '201' in family 'DND' May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: Extension 201 do not disturb is disabled May 11 10:40:44 DEBUG[1316]: Manager received command 'Login' May 11 10:40:44 VERBOSE[1316]: == Parsing '/etc/asterisk/manager.conf': May 11 10:40:44 VERBOSE[1316]: == Parsing '/etc/asterisk/ manager.conf': Found May 11 10:40:44 VERBOSE[1316]: == Parsing '/etc/asterisk/manager_custom.conf': May 11 10:40:44 VERBOSE[1316]: == Parsing '/etc/as terisk/manager_custom.conf': Found May 11 10:40:44 WARNING[1316]: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf May 11 10:40:44 DEBUG[1316]: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer May 11 10:40:44 DEBUG[1316]: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer May 11 10:40:44 DEBUG[1316]: ##### Testing 127.0.0.1 with 0.0.0.0 May 11 10:40:44 DEBUG[1316]: ##### Testing 127.0.0.1 with 127.0.0.0 May 11 10:40:44 VERBOSE[1316]: == Manager 'admin' logged on from 127.0.0.1 May 11 10:40:44 DEBUG[1316]: Manager received command 'command' May 11 10:40:44 DEBUG[1316]: Manager received command '' May 11 10:40:44 DEBUG[1316]: Manager received command 'Logoff' May 11 10:40:44 VERBOSE[1316]: == Manager 'admin' logged off from 127.0.0.1 May 11 10:40:44 DEBUG[1316]: Unable to find key '201' in family 'CW' May 11 10:40:44 VERBOSE[1316]: dialparties.agi: Extension 201 has call waiting disabled May 11 10:40:44 VERBOSE[1316]: -- dialparties.agi: DbSet CALLTRACE/201 to 6047878465 May 11 10:40:44 VERBOSE[1316]: dialparties.agi: Dial string is SIP/201|30|tr May 11 10:40:44 VERBOSE[1316]: -- AGI Script dialparties.agi completed, returning 0 May 11 10:40:44 VERBOSE[1316]: -- Executing Dial("Local/201@from-internal-654b,2", "SIP/201|30|tr") in new stack May 11 10:40:44 DEBUG[1316]: SIMPLE DIAL (NO URL) May 11 10:40:44 DEBUG[1316]: Setting NAT on RTP to 4 May 11 10:40:44 DEBUG[1316]: ##### Testing 192.168.10.20 with 192.168.10.0 May 11 10:40:44 DEBUG[1316]: Outgoing Call for 201 May 11 10:40:44 DEBUG[1316]: Call from user '201' is 1 out of 0 May 11 10:40:44 VERBOSE[1316]: -- Called 201 May 11 10:40:44 VERBOSE[1316]: -- Local/201@from-internal-654b,1 is ringing May 11 10:40:44 DEBUG[1316]: (Provisional) Stopping retransmission (but retaining packet) on '7f1d291e504a2fec47ce09a1667f4da5@192.16 8.10.2' Request 102: Found May 11 10:40:45 DEBUG[1316]: (Provisional) Stopping retransmission (but retaining packet) on '7f1d291e504a2fec47ce09a1667f4da5@192.16 8.10.2' Request 102: Found May 11 10:40:45 VERBOSE[1316]: -- SIP/201-4463 is ringing May 11 10:40:45 VERBOSE[1316]: -- Local/201@from-internal-654b,1 is ringing