Wierd, doing nothing but this shouldn't happen...

[VOIPdani]

Hallo,

kann mir jemand folgendes Warning erklären:

Apr 24 15:09:26 VERBOSE[3100] logger.c:     -- Executing Dial("IAX2/172.21.31.51:4569-1", "Zap/g1/07674xxxxxx") in new stack
Apr 24 15:09:26 VERBOSE[3100] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Apr 24 15:09:26 VERBOSE[3100] logger.c:     -- Called g1/07674xxxxx
Apr 24 15:09:26 WARNING[26707] [B]chan_zap.c: 3 updating callstate, peercallstate 2 to 0[/B]
Apr 24 15:09:30 WARNING[26707] chan_zap.c: [B]3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null[/B]
Apr 24 15:09:30 VERBOSE[26707] logger.c:     -- Channel 0/1, span 3 got hangup, cause 42
Apr 24 15:09:30 VERBOSE[3100] logger.c:     -- Zap/7-1 is circuit-busy
Apr 24 15:09:30 [B]WARNING[3100] chan_zap.c: 3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null[/B]
Apr 24 15:09:30 VERBOSE[3100] logger.c:     -- Hungup 'Zap/7-1'
Apr 24 15:09:30 VERBOSE[3100] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)

Wie kommt das zu Stande?
Wie kann ich das beheben?

lg Dani

 

[VOIPdani]

Bis jetzt hab ich folgendes rausgefunden: Hangup Cause 42 steht für 'switching equipment congestion' (This cause indicates that the switching equipment generating this cause is experiencing a period of high traffic)
Aber zu diesem Zeitpunkt waren meine Leitungen sicher nicht komplett ausgelastet :-(

Weiters glaub ich dass dieses Warning eventuell mit dem Endgerät zusammenhängt: Das Endgerät von dem der Anruf ausgelöst wurde ist ein DECT-Telefon, welches an der alten TK hängt, wobei mein Aubau zur Zeit so aussieht: alte TK=-=Asterisk=-=Obelisk=-=Amtsleitungen

Kann mir vielleicht aufgrund dieser Infos jemand einen Tipp geben?

lg Dani

 

[clan]

Bis jetzt hab ich folgendes rausgefunden: Hangup Cause 42 steht für 'switching equipment congestion' (This cause indicates that the switching equipment generating this cause is experiencing a period of high traffic)
Aber zu diesem Zeitpunkt waren meine Leitungen sicher nicht komplett ausgelastet :-(

Da die Meldung üblicherweise von der VSt kommt geht es nicht um deine Leitungen, sondern um die Leitungen irgendwo auf dem Weg zum angerufenen Teilnehmer. Auf Deutsch nennt sich das Gassenbesetzt. Die Meldung kommt z.B. auch, wenn man versucht ein Handy anzurufen, das sich in einer vollen Funkzelle befindet.

Weiters glaub ich dass dieses Warning eventuell mit dem Endgerät zusammenhängt: Das Endgerät von dem der Anruf ausgelöst wurde ist ein DECT-Telefon, welches an der alten TK hängt, wobei mein Aubau zur Zeit so aussieht: alte TK=-=Asterisk=-=Obelisk=-=Amtsleitungen

Kann mir vielleicht aufgrund dieser Infos jemand einen Tipp geben?

War das Log von Asterisk oder Obelisk? Bei ersteren ist vermutlich das Endgerät oder die alte TK-Anlage schuld, ansonsten dein Provider, der Provider des angerufenen oder irgendwer dazwischen.

 

[VOIPdani]

War das Log von Asterisk oder Obelisk? Bei ersteren ist vermutlich das Endgerät oder die alte TK-Anlage schuld, ansonsten dein Provider, der Provider des angerufenen oder irgendwer dazwischen.

Das Log war von Obelisk, sprich dem Gateway in die Telekom-Welt.
Recht zufriedenstellend ist das nicht...kann ich da gar nichts dagegen machen??

 

[VOIPdani]

Hallo,

das Problem tritt nach wie vor auf und ich weiß nicht recht wo ich ansetzten soll, um das Problem zu lösen.

Apr 26 09:15:11 VERBOSE[13172] logger.c:     -- Executing Dial("IAX2/172.21.1.162:4569-3", "Zap/g1/082061xxxxx") in new stack
Apr 26 09:15:11 VERBOSE[13172] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Apr 26 09:15:11 VERBOSE[13172] logger.c:     -- Called g1/082061xxxxx
Apr 26 09:15:11 WARNING[11516] chan_zap.c: 3 updating callstate, peercallstate 2 to 0
Apr 26 09:15:15 WARNING[11516] chan_zap.c: 3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null
Apr 26 09:15:15 VERBOSE[11516] logger.c:     -- Channel 0/2, span 3 got hangup, cause 42
Apr 26 09:15:15 VERBOSE[13172] logger.c:     -- Zap/8-1 is circuit-busy
Apr 26 09:15:15 WARNING[13172] chan_zap.c: 3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null
Apr 26 09:15:15 VERBOSE[13172] logger.c:     -- Hungup 'Zap/8-1'
Apr 26 09:15:15 VERBOSE[13172] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)
Apr 26 09:15:25 WARNING[13172] pbx.c: Timeout, but no rule 't' in context 'OUTGOING'
Apr 26 09:15:25 VERBOSE[13172] logger.c:     -- Hungup 'IAX2/172.21.1.162:4569-3'

Der Anruf nach extern ist in diesem Zeitpunkt nicht möglich, obwohl ich eine Minute später ohne Probleme auf dieselbe Nummer anrufen hab können.

Edit: In diesem Fall wird der Anruf in der CLI angestossen (über dial...), womit ich meine Theorie, der Fehler würde mit dem Endgerät zusammenhängen verwerfen muss.

Hat denn niemand einen Tipp zur Lösung?

lg Dani

 

[jhansen]

Ne, ich habs halt auch. Der Kunde ist schon echt genervt. Es tritt leider auf mehreren meiner Asterisk Servern auf. Nur scheint es oft unerkannt zu bleiben, wenn es nicht allzu häufig auftritt.

Oftmals geht es nach einem Neustart erstmal wieder. Aber nicht immer.

Also nichts neues.

Sobald ich was weiß, lass ichs dich wissen.

Gruß
Jens

 

[VOIPdani]

So, gestern hab ich mal pri debug span laufen lassen und bei einem Call ist wieder die Fehlermeldung 'Wierd, doing nothing but this....' aufgetreten. Der Log zu dem Call sieht so aus:

Apr 27 15:49:38 VERBOSE[23463] logger.c:     -- Executing Dial("IAX2/172.21.31.51:4569-4", "Zap/g1/72367506") in new stack
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 -- Making new call for cr 128
Apr 27 15:49:38 VERBOSE[23463] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Protocol Discriminator: Q.931 (8)  len=38
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Call Ref: len= 1 (reference 0/0x0) (Originator)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Message type: SETUP (5)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 04Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 043  03Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 043  033  80Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 043  033  803  90Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 043  033  803  903  a3Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 043  033  803  903  a33 ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 >                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 >                              Ext: 1  User information layer 1: A-Law (35)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 18Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 183  01Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 183  013  8aApr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 183  013  8a3 ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Channel ID (len= 3) [ Ext: 1  IntID: Implicit, Other Spare: 0, Exclusive Dchan: 0
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 >                        ChanSel: B2 channel
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3                          ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6cApr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0dApr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  21Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  81Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  37Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  32Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  33Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  36Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  33Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  33Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  35Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  353  31Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  353  313  31Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  353  313  313  33Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  353  313  313  333  31Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 6c3  0d3  213  813  373  323  333  363  333  333  353  313  313  333  313 ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Calling Number (len=15) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 >                           Presentation: Presentation permitted, user number passed network screening (1) '72363351131' ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 70Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  09Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  80Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  37Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  32Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  33Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  36Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  363  37Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  363  373  35Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  363  373  353  30Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  363  373  353  303  36Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > [3 703  093  803  373  323  333  363  373  353  303  363 ]
Apr 27 15:49:38 VERBOSE[23463] logger.c: 3 > Called Number (len=11) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0) '72367506' ]
Apr 27 15:49:38 VERBOSE[23463] logger.c:     -- Called g1/72367506
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < Protocol Discriminator: Q.931 (8)  len=11
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < Call Ref: len= 1 (reference 128/0x80) (Terminator)
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < Message type: STATUS (125)
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 08Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 083  02Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 083  023  82Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 083  023  823  d1Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 083  023  823  d13 ]
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Public network serving the local user (2)
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 <                  Ext: 1  Cause: Unknown (81), class = Invalid message (5) ]
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 14Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 143  01Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 143  013  00Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < [3 143  013  003 ]
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 < Call State (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard (0) Call state: Null (0)
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 -- Processing IE 8 (cs0, Cause)
Apr 27 15:49:38 VERBOSE[19458] logger.c: 3 -- Processing IE 20 (cs0, Call State)
Apr 27 15:49:38 WARNING[19458] chan_zap.c: 3 updating callstate, peercallstate 2 to 0
Apr 27 15:49:42 VERBOSE[19458] logger.c: 3 No response to SETUP message
Apr 27 15:49:42 VERBOSE[19458] logger.c: 3 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Initiated, peerstate Null
Apr 27 15:49:42 WARNING[19458] chan_zap.c: 3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null
Apr 27 15:49:42 VERBOSE[19458] logger.c:     -- Channel 0/2, span 3 got hangup, cause 42
Apr 27 15:49:42 VERBOSE[23463] logger.c:     -- Zap/8-1 is circuit-busy
Apr 27 15:49:42 VERBOSE[23463] logger.c: 3 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Initiated, peerstate Null
Apr 27 15:49:42 WARNING[23463] chan_zap.c: 3 Wierd, doing nothing but this shouldn't happen, ourstate Call Initiated, peerstate Null
Apr 27 15:49:42 VERBOSE[23463] logger.c:     -- Hungup 'Zap/8-1'
Apr 27 15:49:42 VERBOSE[23463] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)
Apr 27 15:49:42 VERBOSE[23463] logger.c:     -- Executing Goto("IAX2/172.21.31.51:4569-4", "r-CONGESTION|1") in new stack
Apr 27 15:49:42 VERBOSE[23463] logger.c:     -- Goto (OUTGOING,r-CONGESTION,1)
Apr 27 15:49:42 WARNING[23463] pbx.c: Channel 'IAX2/172.21.31.51:4569-4' sent into invalid extension 'r-CONGESTION' in context 'OUTGOING', but no invalid handler
Apr 27 15:49:42 VERBOSE[23463] logger.c:     -- Hungup 'IAX2/172.21.31.51:4569-4'

Der Hangupcause 81 bedeuted: Cause No. 81 - invalid call reference value.
This cause indicates that the equipment sending this cause has received a message with a call reference which is not currently in use on the user-network interface.

Wobei für mich die Abfrage des DIALSTATUS viel aufschlussreicher war, da diese CONGESTION ergab.

Diese Fehlermeldung scheint wirklich für ein Gassenbesetzt zu stehen.

lg Dani

 

[clan]

Der Hangupcause 81 bedeuted: Cause No. 81 - invalid call reference value.
This cause indicates that the equipment sending this cause has received a message with a call reference which is not currently in use on the user-network interface.

Wobei für mich die Abfrage des DIALSTATUS viel aufschlussreicher war, da diese CONGESTION ergab.

Diese Fehlermeldung scheint wirklich für ein Gassenbesetzt zu stehen.

Leider gibt der Asterisk immer BUSY/CONGESTION, wenn er mit dem Cause nichts anfangen kann.

Aufschlussreicher ist Cause 81 zusammen mit dem Dump der Setup-Message: die call reference ist Null, was, wenn ich die ETSI richtig lese, verboten ist.

Bei fehlerhaften Messages sendet die Gegenstelle üblicherweise eine Status-Message.

Ist wohl ein Fehler in der Implementierung. Vielleicht habe ich nachher noch Zeit für eine Mail an kpj.