Translation to slin failed

[Timmbo]

Hi,

habe diese Fehlermeldung in den Logs

Jul  8 16:17:59 ERROR[27542] channel.c: Translation to slin failed,
dropping frame for spies

Es ist aber keine Codec Umsetzung aktiv, was kann das denn bedeuten?

Grüße und Thx.
Timm

 

[laureen]

ist die "format_sln.so" geladen? welche codecs verwendet das gespräch? ist da eventuell eine aufnahme/mailbox/playback/moh aktiv, wenn das passiert?

ich weiss, dass du das im log und nicht auf der konsole siehst, schalt mal den log etwas "verboser" in der logger.conf, vielleicht sieht man dann mehr...

grüße,
laureen

 

[Timmbo]

Hi laureen,

das verbose steht auf 42.
In der sip.conf ist nur alaw, µlaw und gsm als allow eingetragen.
Ich denke das Modul ist nicht aktiv, da der codec nicht verwendet wird.
Das eigenartige ist, das während eines Gesprächs es plötzlich abbricht,
aber nur ankommende die über ISDN kommen, und auch nicht jedes.

Grüße
Timm

 

[Ottone]

Entweder seit 1.2 oder 1.4 (was ich eher glaube zu erinnern) ist das interne Audioformat slinear, d.h. es wird immer transkodiert - sofern denn RTP durch Asterisk fliesst.

 

[laureen]

@Ottone: das würde dann auch erklären, warum bei ISDN anrufen von sip phone->asterisk->isdn-karte prozessorlast entsteht, obwohl alles alaw ist...was für ein verlust, werde das mal auf einer asterisk 1.4.21.1 überprüfen und gebe bescheid...

@Timmbo: welche version von asterisk verwendest du denn auf besagtem rechner?

grüße,
laureen

 

[Timmbo]

Hi laureen,

es ist 1.2.27 und das Modul ist doch geladen, habe es überprüft.
Interessanterweise passiert das bei ausgehenden Calls, die ja über einen VoIP-Provider(iax2) gehen nicht.
Kann das daran liegen, das kein RTP-Stream läuft, da die Audiodaten im iax2 Stream mitlaufen?

Grüße
Timm

 

[laureen]

könntest du mal in der "/etc/asterisk/logger.conf" folgende zeile in den abschnitt "[logfiles]" einfügen, um zu sehen, welche dinge kurz vor dieser fehlermeldung passieren:

...
debug => debug,notice,warning,error,verbose,dtmf
...

und ein "logger reload" auf der asterisk konsole nicht vergessen!
sobald du die fehlermeldung dann hast, kannst du die zeile wieder auskommentieren. im file "/var/log/asterisk/debug" hast du dann die ausgabe, poste da mal die letzten 20 zeilen vor der fehlermeldung, vielleicht kann man da genaueres sehen.

[EDIT]ein blick in den syslog könnte eventuell fehler des isdn treibers ersichtlich machen[/EDIT]

grüße,
laureen

 

[Timmbo]

Hi,

habe hier ein log von gestern.

Jul  8 16:57:14 DEBUG[28289] pbx.c: Function result is ''
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Set("SIP/16-b759ce38", "sipcount=|counter=0") in new stack
Jul  8 16:57:14 DEBUG[28289] pbx.c: Expression result is '0'
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
While("SIP/16-b759ce38", "0") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Jumping to priority 10
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
EndWhile("SIP/16-b759ce38", "") in new stack
Jul  8 16:57:14 DEBUG[28289] pbx.c: Expression result is '5'
Jul  8 16:57:14 DEBUG[28289] pbx.c: Function result is '5'
Jul  8 16:57:14 DEBUG[28289] pbx.c: Function result is ''
Jul  8 16:57:14 DEBUG[28289] pbx.c: Function result is ''
Jul  8 16:57:14 DEBUG[28289] pbx.c: Expression result is '0'
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
While("SIP/16-b759ce38", "0") in new stack
Jul  8 16:57:14 DEBUG[28289] pbx.c: Expression result is '1'
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
GotoIf("SIP/16-b759ce38", "1?ef") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Goto (enum,436644429618,16)
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Goto("SIP/16-b759ce38", "enum-lcr|00436644429618|1") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Goto
(enum-lcr,00436644429618,1)
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Set("SIP/16-b759ce38", "CALLERID(number)=16") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Goto("SIP/16-b759ce38", "nachenum16|06644429618|1") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Goto
(nachenum16,06644429618,1)
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
ChanIsAvail("SIP/16-b759ce38", "iax2/06644429618@connect20716_1|j") in
new stack
Jul  8 16:57:14 DEBUG[28289] chan_iax2.c: We're hanging up
IAX2/connect20716_1-3 now...
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Hungup
'IAX2/connect20716_1-3'
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Set("SIP/16-b759ce38", "CALLERID(number)=+437662896316") in new stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Executing
Dial("SIP/16-b759ce38", "iax2/connect20716_1/06644429618|60|TW") in new
stack
Jul  8 16:57:14 VERBOSE[28289] logger.c:     -- Called
connect20716_1/06644429618
Jul  8 16:57:14 DEBUG[1462] chan_iax2.c: Immediately destroying 3,
having received INVAL
Jul  8 16:57:14 VERBOSE[1462] logger.c:     -- Call accepted by
83.137.41.34 (format ulaw)
Jul  8 16:57:14 VERBOSE[1462] logger.c:     -- Format for call is ulaw
Jul  8 16:57:14 DEBUG[1462] chan_iax2.c: Ooh, voice format changed to 4
Jul  8 16:57:14 DEBUG[28289] channel.c: Building translator from alaw to
SLINEAR for spies on channel SIP/16-b759ce38
Jul  8 16:57:14 DEBUG[28289] channel.c: Building translator from alaw to
SLINEAR for spies on channel SIP/16-b759ce38
Jul  8 16:57:16 VERBOSE[1357] logger.c:     -- Remote UNIX connection
Jul  8 16:57:16 VERBOSE[28307] logger.c:     -- Remote UNIX connection
disconnected
Jul  8 16:57:19 VERBOSE[28289] logger.c:     -- IAX2/connect20716_1-8 is
proceeding passing it to SIP/16-b759ce38
Jul  8 16:57:20 VERBOSE[1357] logger.c:     -- Remote UNIX connection
Jul  8 16:57:20 VERBOSE[28313] logger.c:     -- Remote UNIX connection
disconnected
Jul  8 16:57:21 VERBOSE[28289] logger.c:     -- IAX2/connect20716_1-8 is
making progress passing it to SIP/16-b759ce38
Jul  8 16:57:21 VERBOSE[28289] logger.c:     -- IAX2/connect20716_1-8 is
ringing
Jul  8 16:57:21 DEBUG[28289] channel.c: Driver for channel
'SIP/16-b759ce38' does not support indication 3, emulating it
Jul  8 16:57:21 DEBUG[28289] channel.c: Prodding channel 'SIP/16-b759ce38'
Jul  8 16:57:21 ERROR[28289] channel.c: Translation to slin failed,
dropping frame for spies

 

[laureen]

der iax peer "connect20716_1" verwendet ulaw, das sip telefon verwendet alaw, deswegen muss asterisk transcodieren. versuche mal, nur den codec alaw für den iax peer zu erlauben (iax.conf):

...
[connect20716_1]
...
disallow = all
allow = alaw
...

meine vermutung: scheinbar ist die ulaw implementierung bei diesem peer irgendwie "anders" als auf der asterisk.

grüße,
laureen

 

[Timmbo]

Hi laureen,


aber bei abgehenden funzt es ja, nur bei eingehenden (mISDN) tritt das Problem auf.
Obwohl die codecreihenfolge erst alaw und dann ulaw ist, nimmt er bei VoIP den ulaw, kannst Du Dir das erklären, denn lt. Provider hat der den alaw auch an erster stelle stehen?

Werde das aber trotzdem mal setzen.

Thx
Timm

 

[laureen]

aber der log zeigt ja an, dass sich das format auf 4 (ulaw) ändert!

auf welche codecs ist denn dein mISDN eingestellt? kenn mich leider nicht mit mISDN aus, und ob man dort codecs einstellen kann oder nicht?!?!

grüße,
laureen

 

[Timmbo]

Hi laureen,

heute ist es nicht aufgetreten.
Ich habe gestern noch auf den Snom Telefonen entdeckt, dass da kaputte Registrar auf "off" und userhone auf "on" gestanden ist, beides ist bei der Verwendung mit Asterisk genau andersherum einzustellen.
Werde es verfolgen und weiter berichten.

Thx
Timm

 

[Timmbo]

Hi laureen,


das habe ich auch noch mehrmals entdeckt, das ist wohl der Grund warum es öfters zu Abbrüchen kommt:

Tue Jul  8 10:38:12 2008: P[ 1]   --> !! Got Busy in Connected State !?!
ast:mISDN/1-1

Grüße
Timm

 

[laureen]

sorry, wenn ich sarkastisch klinge, aber das zählt zu den gründen, warum ich misdn und chan_capi nicht mehr verwende...

welche isdn hardware verwendest du derzeit mit misdn (hfc-s karten, avm, etc.)?

grüße,
laureen

 

[Timmbo]

Hi laureen,


es sind hfc Karten.Single und mehrfach, im aktuellen Fall sind es mehrfach.

Grüße
Timm