No audio on chan_skinny 7941G


#1

Hi all,
as many others, first post :smiley:

I’ve got some problem with my Cisco 7941G and Asterisk (1.8.7.0). Before listing my problem, I want to explicit that I have a Cisco 7941G (firmware: SCCP41.8-4-2S), a SIP phone (Polycom IP320 SIP or Zoiper Free, but this is not influent) and Asterisk on a Ubuntu Server.

Below there is my skinny.conf:


[general]
disallow=all
allow=ulaw
bindaddr=0.0.0.0
bindport=2000
dateformat=D-M-Y
keepalive=120

[lines]

[8301]
nat=yes
callerid=“Giuseppe” <8301>
regexten=8301
context=operatori_rm
linelabel=“Giuseppe”

[devices]

[support]
device=SEP001D4561xxxx
line=8301
host=192.168.200.110


When I try to make a call from SIp (Policom or Zoiper) toward the Cisco, I can’t hear anything from SIP phone but I only hear the “ready tone” (the same you can hear when you pick up the hook) for the entire connection time. The related skinny debug is the following one:


== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [8301@operatori_rm:1] Monitor(“SIP/8102-00000003”, “wav,1318845010.7,m”) in new stack
– Executing [8301@operatori_rm:2] Dial(“SIP/8102-00000003”, “Skinny/8301,60”) in new stack
– skinny_request(8301)
skinny_new: tmp->nativeformats=0x4 (ulaw) fmt=ulaw
skinny_get_rtp_peer() Channel = Skinny/8301@support-5
– skinny_call(Skinny/8301@support-5)
Displaying Prompt Status 'Ring-In’
Setting Callinfo to Giuseppe Cassibba(8102) from Giuseppe(8301) on support(1)
Setting ringer mode to ‘2’.
– Called Skinny/8301
== Found device: support
– Skinny/8301@support-5 is ringing
RECEIVED UNKNOWN MESSAGE TYPE: 49
Setting ringer mode to ‘1’.
– Skinny/8301@support-5 answered SIP/8102-00000003
== Found device: support
– Asked to indicate ‘Media Source Update’ condition on channel Skinny/8301@support-5
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :105:
– Asked to indicate ‘Media Source Update’ condition on channel Skinny/8301@support-5
– Hanging up support/5
> Killing only sub 5
Clearing Prompt
Setting ringer mode to ‘1’.
Clearing Display
== Found device: support
== Spawn extension (operatori_rm, 8301, 2) exited non-zero on 'SIP/8102-00000003’
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :9a: M
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
RECEIVED UNKNOWN MESSAGE TYPE: 49


When I try to call the SIP phone from my 7941G, the result is the same from heared point of view, but after about 5 secs the call drops.


RECEIVED UNKNOWN MESSAGE TYPE: 49
Received Softkey Event: New Call(0/0)
skinny_new: tmp->nativeformats=0x4 (ulaw) fmt=ulaw
Call-id: 1
Clearing Display
– Starting simple switch on '8301@support’
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :9a: M
Received Enbloc Call: 8102
skinny_new: tmp->nativeformats=0x4 (ulaw) fmt=ulaw
Clearing Display
== Found device: support
– Executing [8102@operatori_rm:1] Dial(“Skinny/8301@support-2”, “SIP/8102,10,m”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
skinny_get_rtp_peer() Channel = Skinny/8301@support-2
skinny_get_rtp_peer() Using AST_RTP_GLUE_RESULT_LOCAL
– Called SIP/8102
– Started music on hold, class ‘default’, on Skinny/8301@support-2
– Asked to indicate ‘Remote end is making Progress’ condition on channel Skinny/8301@support-2
Displaying Prompt Status 'Call Progress’
Setting Callinfo to Giuseppe(8301) from 8102(8102) on support(1)
– Asked to indicate ‘Connected Line’ condition on channel Skinny/8301@support-2
Displaying Prompt Status 'Ring-Out’
Setting Callinfo to Giuseppe(8301) from Giuseppe Cassibba(8102) on support(1)
– Asked to indicate ‘Connected Line’ condition on channel Skinny/8301@support-2
Displaying Prompt Status 'Call Progress’
Setting Callinfo to Giuseppe(8301) from Giuseppe Cassibba(8102) on support(1)
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :9a: M
– SIP/8102-00000000 is ringing
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :105:
– Asked to indicate ‘Connected Line’ condition on channel Skinny/8301@support-2
Displaying Prompt Status 'Call Progress’
Setting Callinfo to Giuseppe(8301) from Giuseppe Cassibba(8102) on support(1)
– SIP/8102-00000000 answered Skinny/8301@support-2
– Stopped music on hold on Skinny/8301@support-2
skinny_answer(Skinny/8301@support-2) on 8301@support-2
Setting Callinfo to Giuseppe Cassibba(8102) from 8102(8102) on support(1)
== Found device: support
Displaying Prompt Status ‘Connected’
– Asked to indicate ‘Stop tone’ condition on channel Skinny/8301@support-2
– Asked to indicate ‘Media Source Update’ condition on channel Skinny/8301@support-2
skinny_get_rtp_peer() Channel = Skinny/8301@support-2
skinny_get_rtp_peer() Using AST_RTP_GLUE_RESULT_LOCAL
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
– Asked to indicate ‘Stop tone’ condition on channel Skinny/8301@support-1
– Hanging up support/1
> Killing inactive sub 1
== Found device: support
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r
Skinny Client failed to authenticate in 30 seconds
[Oct 17 12:00:20] WARNING[29692]: chan_skinny.c:6329 destroy_session: Trying to delete nonexistent session 0xb4a94690?
RECEIVED UNKNOWN MESSAGE TYPE: 49
Clearing Prompt
[Oct 17 12:00:21] ERROR[30155]: res_config_sqlite.c:847 cdr_handler: attempt to write a readonly database
== Spawn extension (operatori_rm, 8102, 1) exited non-zero on ‘Skinny/8301@support-2’
– Hanging up support/2
> Killing only sub 2
Clearing Prompt
Setting ringer mode to ‘1’.
Clearing Display
== Found device: support
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :9a: M
Received Alarm Message: 32: Name=SEP001D4561xxxx Load= SCCP41.8-4-2S : Invalid SCCP message! : ID :83: r


In the last case, the channel appears to remain open: after the call end I continue seeing the “End Call” softkey selection, but its pressure gives me back the folloing asterisk message:


[Oct 17 12:03:41] WARNING[29691]: chan_skinny.c:1674 find_subchannel_by_instance_reference: Could not find subchannel with reference ‘1’ on 'support’
Received Softkey Event: End Call(1/1)


(other note: but the call was delivered by “support-2” channel, why is it trying to kill subchannel 1??? :confused: )
Another asterisk message I see preriodically is the following one (maybe useful):


Skinny Client failed to authenticate in 30 seconds
[Oct 17 11:49:34] WARNING[28488]: chan_skinny.c:6329 destroy_session: Trying to delete nonexistent session 0xa30a5a8?


I would like to maintain a skinny firmware because i have some phones with their default firmware and I don’t have the SIP one…
Very last note: I also tried the same configuration with a cisco 7961 (with a bit older firmware) and it worked perfectly!

Can anyone help me?
Thanks, Giuseppe.


#2

Might be a bug with the phone firmware or might also be some kind of NAT issue. Things you might want to try:

  1. Try upgrading the phone firmware.
  2. Try using a different channel driver (chan-sccp-b)
  3. Try the SIP firmware

Stoyan


#3

Thank you, SGM.
I solved my problem by DOWNgrading my phone firmware to SCCP41.8-0-4SR3S :smiley:!
In previous firmware version it seemed to me as RTP audio streaming was not delivered to Asterisk, but it tried to directly point to the other party (SIP)… :confused: I don’t know if it can be a bug or an error in my xml configurations or in my skinny.conf…

However, thank you!

Giuseppe.