[Resolved] incoming call goes directly to Voice mail


#1

the proble is as follows

when you ring this extention ( 107 )
the call goesd directly to VM
and not to the one that says 'caller is not picking up’
but to where it says ‘caller is on the phone right now’

i am guessing that maybe the line is ‘frozen’ some where
restarting the phone did not help.

CLI output

== CDR updated on Zap/4-1
– Executing Macro(“Zap/4-1”, “exten-vm|107|107”) in new stack
– Executing Macro(“Zap/4-1”, “user-callerid”) in new stack
– Executing DBget(“Zap/4-1”, “AMPUSER=DEVICE//user”) in new stack
– DBget: varname=AMPUSER, family=DEVICE, key=/user
– DBget: Value not found in database.
– Executing DBget(“Zap/4-1”, “AMPUSERCIDNAME=AMPUSER//cidname”) in new stack
– DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=/cidname
– DBget: Value not found in database.
– Executing GotoIf(“Zap/4-1”, “1?5”) in new stack
– Goto (macro-user-callerid,s,5)
– Executing NoOp(“Zap/4-1”, "Using CallerID ") in new stack
– Executing SetVar(“Zap/4-1”, “FROMCONTEXT=exten-vm”) in new stack
– Executing Macro(“Zap/4-1”, “record-enable|107|IN”) in new stack
– Executing GotoIf(“Zap/4-1”, “0 > 0?2:4”) in new stack
– Goto (macro-record-enable,s,4)
– Executing AGI(“Zap/4-1”, “recordingcheck|20060511-130428|1147367062.888”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20060511-130428|1147367062.888: Inbound recording not enabled
– AGI Script recordingcheck completed, returning 0
– Executing NoOp(“Zap/4-1”, “No recording needed”) in new stack
– Executing Macro(“Zap/4-1”, “dial|15|tr|107”) in new stack
– Executing GotoIf(“Zap/4-1”, “0?4:2”) in new stack
– Goto (macro-dial,s,2)
– Executing GotoIf(“Zap/4-1”, “0?5:4”) in new stack
– Goto (macro-dial,s,4)
– Executing AGI(“Zap/4-1”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
– dialparties.agi: priority = 4
– dialparties.agi: callingani2 = 0
– dialparties.agi: accountcode =
– dialparties.agi: channel = Zap/4-1
– dialparties.agi: callerid = unknown
– dialparties.agi: context = macro-dial
– dialparties.agi: callington = 0
– dialparties.agi: dnid = unknown
– dialparties.agi: request = dialparties.agi
– dialparties.agi: calleridname = unknown
– dialparties.agi: extension = s
– dialparties.agi: language = en
– dialparties.agi: uniqueid = 1147367062.888
– dialparties.agi: callingpres = 0
– dialparties.agi: type = Zap
– dialparties.agi: rdnis = unknown
– dialparties.agi: callingtns = 0
– dialparties.agi: enhanced = 0.0
dialparties.agi: Caller ID is not set
dialparties.agi: Methodology of ring is ‘none’
– dialparties.agi: Added extension 107 to extension map
– dialparties.agi: Extension 107 cf is disabled
– dialparties.agi: Extension 107 do not disturb is disabled
– dialparties.agi: Checking CW and CFB status for extension 107
== Parsing ‘/etc/asterisk/manager.conf’: Found
== Parsing ‘/etc/asterisk/manager_custom.conf’: Found
== Manager ‘admin’ logged on from 127.0.0.1
– dialparties.agi: Correct AMPMGRUSER and AMPMGRPASS
dialparties.agi: Extension 107 is not available to be called
dialparties.agi: Extension 107 has call waiting disabled
– AGI Script Executing Application: (NoOp) Options: ()
– AGI Script dialparties.agi completed, returning 0
– Executing NoOp(“Zap/4-1”, “Returned from dialparties with no extensions to call”) in new stack
– Executing SetVar(“Zap/4-1”, “DIALSTATUS=BUSY”) in new stack
– Executing GotoIf(“Zap/4-1”, “0?s-BUSY|1”) in new stack
– Executing GotoIf(“Zap/4-1”, “0?s-BUSY|1”) in new stack
– Executing NoOp(“Zap/4-1”, “Sending to Voicemail box 107”) in new stack
– Executing Macro(“Zap/4-1”, “vm|107|BUSY”) in new stack
– Executing Macro(“Zap/4-1”, “user-callerid”) in new stack
– Executing DBget(“Zap/4-1”, “AMPUSER=DEVICE//user”) in new stack
– DBget: varname=AMPUSER, family=DEVICE, key=/user
– DBget: Value not found in database.
– Executing DBget(“Zap/4-1”, “AMPUSERCIDNAME=AMPUSER//cidname”) in new stack
– DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=/cidname
– DBget: Value not found in database.
– Executing GotoIf(“Zap/4-1”, “1?5”) in new stack
– Goto (macro-user-callerid,s,5)
– Executing NoOp(“Zap/4-1”, "Using CallerID ") in new stack
– Executing Goto(“Zap/4-1”, “s-BUSY|1”) in new stack
– Goto (macro-vm,s-BUSY,1)
– Executing VoiceMail(“Zap/4-1”, “b107”) in new stack
– Playing ‘vm-theperson’ (language ‘en’)

syslog debug output

i might have snagged a few extra lines in the end and begining
which dont relate to ext we are having problems with

May 11 12:57:01 phone crond(pam_unix)[25341]: session closed for user root
May 11 12:57:10 192.168.1.107 GS_LOG: [00:0B:82:05:3B:0A][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:18 192.168.1.111 GS_LOG: [00:0B:82:05:3E:EB][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:18 192.168.1.108 GS_LOG: [00:0B:82:05:3B:0E][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:18 192.168.1.109 GS_LOG: [00.0B.82.05.3B.0B][000] Received SIP message: 7
May 11 12:57:18 192.168.1.106 GS_LOG: [00:0B:82:05:3B:0D][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:18 192.168.1.109 GS_LOG: [00.0B.82.05.3B.0B][000] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:18 192.168.1.103 GS_LOG: [00:0B:82:05:39:E9][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:19 192.168.1.104 GS_LOG: [00:0b:82:07:33:97][000] Received SIP message: 7
May 11 12:57:19 192.168.1.104 GS_LOG: [00:0b:82:07:33:97][000] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:57:39 phone kernel: IN=eth1 OUT= MAC=00:11:2f:75:a9:00:00:11:20:79:d9:0c:08:00 SRC=209.73.196.11 DST=70.107.224.9 LEN=48 TOS=0x00 PREC=0x00 TTL=117 ID=42140 DF PROTO=TCP SPT=1872 DPT=25 WINDOW=65535 RES=0x00 SYN URGP=0
May 11 12:57:42 phone kernel: IN=eth1 OUT= MAC=00:11:2f:75:a9:00:00:11:20:79:d9:0c:08:00 SRC=209.73.196.11 DST=70.107.224.9 LEN=48 TOS=0x00 PREC=0x00 TTL=117 ID=42154 DF PROTO=TCP SPT=1872 DPT=25 WINDOW=65535 RES=0x00 SYN URGP=0
May 11 12:57:47 phone php: agi Object
May 11 12:57:47 phone php: (
May 11 12:57:47 phone php: [request] => Array
May 11 12:57:47 phone php: (
May 11 12:57:47 phone php: [agi_request] => recordingcheck
May 11 12:57:47 phone php: [agi_channel] => Zap/3-1
May 11 12:57:47 phone php: [agi_language] => en
May 11 12:57:47 phone php: [agi_type] => Zap
May 11 12:57:47 phone php: [agi_uniqueid] => 1147366663.880
May 11 12:57:47 phone php: [agi_callerid] => unknown
May 11 12:57:47 phone php: [agi_calleridname] => unknown
May 11 12:57:47 phone php: [agi_callingpres] => 0
May 11 12:57:47 phone php: [agi_callingani2] => 0
May 11 12:57:47 phone php: [agi_callington] => 0
May 11 12:57:47 phone php: [agi_callingtns] => 0
May 11 12:57:47 phone php: [agi_dnid] => unknown
May 11 12:57:47 phone php: [agi_rdnis] => unknown
May 11 12:57:47 phone php: [agi_context] => macro-record-enable
May 11 12:57:47 phone php: [agi_extension] => s
May 11 12:57:47 phone php: [agi_priority] => 4
May 11 12:57:47 phone php: [agi_enhanced] => 0.0
May 11 12:57:47 phone php: [agi_accountcode] =>
May 11 12:57:47 phone php: )
May 11 12:57:47 phone php:
May 11 12:57:48 phone php: [config] => Array
May 11 12:57:48 phone php: (
May 11 12:57:48 phone php: [festival] => Array
May 11 12:57:48 phone php: (
May 11 12:57:48 phone php: [text2wave] => /usr/src/festival/bin/text2wave
May 11 12:57:48 phone php: [tempdir] => /var/lib/asterisk/sounds/tmp/
May 11 12:57:48 phone php: )
May 11 12:57:48 phone php:
May 11 12:57:48 phone php: [phpagi] => Array
May 11 12:57:48 phone php: (
May 11 12:57:48 phone php: [error_handler] => 1
May 11 12:57:48 phone php: [debug] =>
May 11 12:57:48 phone php: [admin] =>
May 11 12:57:48 phone php: [tempdir] => /var/spool/asterisk//tmp/
May 11 12:57:48 phone php: )
May 11 12:57:48 phone php:
May 11 12:57:48 phone php: )
May 11 12:57:48 phone php:
May 11 12:57:48 phone php: [asmanager] =>
May 11 12:57:48 phone php: [in] => Resource id #3
May 11 12:57:48 phone php: [out] => Resource id #4
May 11 12:57:48 phone php: [socket] =>
May 11 12:57:48 phone php: [audio] =>
May 11 12:57:48 phone php: )
May 11 12:57:48 phone php:
May 11 12:57:48 phone php: Inbound recording not enabled
May 11 12:57:48 phone kernel: IN=eth1 OUT= MAC=00:11:2f:75:a9:00:00:11:20:79:d9:0c:08:00 SRC=209.73.196.11 DST=70.107.224.9 LEN=48 TOS=0x00 PREC=0x00 TTL=117 ID=42168 DF PROTO=TCP SPT=1872 DPT=25 WINDOW=65535 RES=0x00 SYN URGP=0
May 11 12:58:01 192.168.1.107 GS_LOG: [00:0B:82:05:3B:0A][000][FFFF][01000810] Send SIP message: 200 To 192.168.1.15:5060
May 11 12:58:01 phone crond(pam_unix)[25361]: session opened for user root by (uid=0)
May 11 12:58:01 phone crond(pam_unix)[25361]: session closed for user root
May 11 12:58:02 phone kernel: IN=eth1 OUT= MAC=00:11:2f:75:a9:00:00:11:20:79:d9:0c:08:00 SRC=209.73.196.11 DST=70.107.224.9 LEN=48 TOS=0x00 PREC=0x00 TTL=117 ID=42260 DF PROTO=TCP SPT=1887 DPT=25 WINDOW=65535 RES=0x00 SYN URGP=0


#2

the problem was an imporoperly hanged up call

in the CLI

sip show channels

the info you are looking for should look as follows
SIP/-
ie. SIP/111-2e4t

then you use that in
soft hangup SIP/111-2e4t

this will hangup the unruly call

enjoy