The following is my log:
Feb 3 10:58:17 VERBOSE[2623] logger.c: – Registered SIP ‘202’ at 192.168.0.106 port 5060 expires 1800
Feb 3 10:58:24 DEBUG[2623] chan_sip.c: Stopping retransmission on ‘07ee29f81e1e742b11d9f38138f20372@192.168.0.105’ of Request 102: Match Found
Feb 3 10:58:32 DEBUG[2623] chan_sip.c: Auto destroying call '7AE1FAF173274E6BA431FD554985BFB8@192.168.0.105’
Feb 3 10:58:55 DEBUG[2623] chan_sip.c: Setting NAT on RTP to 0
Feb 3 10:58:55 DEBUG[2623] chan_sip.c: Stopping retransmission on ‘733E58B6-D3F7-4787-821D-18FB9D1B842C@192.168.0.222’ of Response 36820: Match Found
Feb 3 10:58:55 DEBUG[2623] chan_sip.c: Setting NAT on RTP to 0
Feb 3 10:58:55 DEBUG[2623] chan_sip.c: Checking SIP call limits for device 201
Feb 3 10:58:55 DEBUG[2623] chan_sip.c: build_route: Contact hop:
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing Macro(“SIP/201-8760”, “exten-vm|novm|202”) in new stack
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing Macro(“SIP/201-8760”, “user-callerid”) in new stack
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing DBget(“SIP/201-8760”, “AMPUSER=DEVICE/201/user”) in new stack
Feb 3 10:58:55 VERBOSE[3387] logger.c: – DBget: varname=AMPUSER, family=DEVICE, key=201/user
Feb 3 10:58:55 VERBOSE[3387] logger.c: – DBget: set variable AMPUSER to 201
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing DBget(“SIP/201-8760”, “AMPUSERCIDNAME=AMPUSER/201/cidname”) in new stack
Feb 3 10:58:55 VERBOSE[3387] logger.c: – DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=201/cidname
Feb 3 10:58:55 VERBOSE[3387] logger.c: – DBget: set variable AMPUSERCIDNAME to Borin
Feb 3 10:58:55 DEBUG[3387] pbx.c: Expression result is '0’
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “0?5”) in new stack
Feb 3 10:58:55 DEBUG[3387] pbx.c: Not taking any branch
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing SetCallerID(“SIP/201-8760”, ““Borin” <201>”) in new stack
Feb 3 10:58:55 VERBOSE[3387] logger.c: – Executing NoOp(“SIP/201-8760”, “Using CallerID “Borin” <201>”) in new stack
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Executing SetVar(“SIP/201-8760”, “FROMCONTEXT=exten-vm”) in new stack
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Executing Macro(“SIP/201-8760”, “record-enable|202|IN”) in new stack
Feb 3 10:58:56 DEBUG[3387] pbx.c: Function result is '0’
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “0 > 0?2:4”) in new stack
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Goto (macro-record-enable,s,4)
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Executing AGI(“SIP/201-8760”, “recordingcheck|20000203-105856|949550335.15”) in new stack
Feb 3 10:58:56 VERBOSE[3387] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Feb 3 10:58:58 VERBOSE[3387] logger.c: recordingcheck|20000203-105856|949550335.15: Inbound recording not enabled
Feb 3 10:58:58 VERBOSE[3387] logger.c: – AGI Script recordingcheck completed, returning 0
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing NoOp(“SIP/201-8760”, “No recording needed”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing Macro(“SIP/201-8760”, “dial|15|tr|202”) in new stack
Feb 3 10:58:58 DEBUG[3387] pbx.c: Expression result is '0’
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “0?4:2”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Goto (macro-dial,s,2)
Feb 3 10:58:58 DEBUG[3387] pbx.c: Function result is ‘0’
Feb 3 10:58:58 DEBUG[3387] pbx.c: Expression result is ‘0’
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “0?5:4”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Goto (macro-dial,s,4)
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing AGI(“SIP/201-8760”, “dialparties.agi”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
Feb 3 10:58:58 VERBOSE[3387] logger.c: – AGI Script dialparties.agi completed, returning 0
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing NoOp(“SIP/201-8760”, “Returned from dialparties with no extensions to call”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing SetVar(“SIP/201-8760”, “DIALSTATUS=BUSY”) in new stack
Feb 3 10:58:58 WARNING[3387] ast_expr2.y: non-numeric argument
Feb 3 10:58:58 DEBUG[3387] pbx.c: Expression result is ‘0’
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “0?s-BUSY|1”) in new stack
Feb 3 10:58:58 DEBUG[3387] pbx.c: Not taking any branch
Feb 3 10:58:58 DEBUG[3387] pbx.c: Expression result is ‘1’
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing GotoIf(“SIP/201-8760”, “1?s-BUSY|1”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Goto (macro-exten-vm,s-BUSY,1)
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing NoOp(“SIP/201-8760”, “Extension is reporting BUSY and has no Voicemail”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing Busy(“SIP/201-8760”, “”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: == Spawn extension (macro-exten-vm, s-BUSY, 2) exited non-zero on ‘SIP/201-8760’ in macro ‘exten-vm’
Feb 3 10:58:58 VERBOSE[3387] logger.c: == Spawn extension (from-internal, 202, 1) exited non-zero on ‘SIP/201-8760’
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing Macro(“SIP/201-8760”, “hangupcall”) in new stack
Feb 3 10:58:58 DEBUG[2623] chan_sip.c: Stopping retransmission on ‘733E58B6-D3F7-4787-821D-18FB9D1B842C@192.168.0.222’ of Response 36821: Match Not Found
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing ResetCDR(“SIP/201-8760”, “w”) in new stack
Feb 3 10:58:58 DEBUG[3387] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Feb 3 10:58:58 DEBUG[3387] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES (‘2000-02-03 10:58:55’,’“Borin” <201>’,‘201’,‘202’,‘from-internal’, ‘SIP/201-8760’,’’,‘ResetCDR’,‘w’,3,0,‘NO ANSWER’,3,’’,‘949550335.15’)
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing NoCDR(“SIP/201-8760”, “”) in new stack
Feb 3 10:58:58 WARNING[3387] cdr.c: CDR on channel ‘SIP/201-8760’ not posted
Feb 3 10:58:58 WARNING[3387] cdr.c: CDR on channel ‘SIP/201-8760’ lacks end
Feb 3 10:58:58 VERBOSE[3387] logger.c: – Executing Wait(“SIP/201-8760”, “5”) in new stack
Feb 3 10:58:58 VERBOSE[3387] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on ‘SIP/201-8760’ in macro 'hangupcall’
Feb 3 10:58:58 VERBOSE[3387] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/201-8760’
Feb 3 10:58:58 DEBUG[3387] chan_sip.c: update_call_counter(201) - decrement call limit counter
Feb 3 10:58:58 DEBUG[3387] chan_sip.c: update_call_counter(201) - decrement call limit counter
Looking for your kind advice.
Borin