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