[Problem] Debug IP Anrufe die plötzlich unterbrochen werden (Telekom IP)

vwittich

Neuer User
Mitglied seit
31 Okt 2004
Beiträge
90
Punkte für Reaktionen
0
Punkte
6
Hallo zusammen,

uns plagen schon eine ganze Weile Anrufe die plötzlich abgebrochen werden. Ich habe nun endlich mal in dem Debug Log einen Abbruch-Kandidat erwischt (das Gespräch wurde nach 1:05 Minuten beendet) und wollte hier um Hilfe bei der Ursachen Forschung fragen! Folgende Daten:

Eingehender Anruf von 0693000000
an unsere Nummer: 0603333333
lokaler IP-Nr.: 1111
Asterisk IP: 10.0.0.2 (lokal) 87.000.000.28 (extern)
Asterisk 1.8.10.1

Ich habe versucht die entschiedene Stelle im Log zu finden kann hier aber keine Erklärung für den Abbruch erkennen:

Code:
[Mar 18 13:48:51] DEBUG[21637] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff29c6f8a98'
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Received bye, issuing owner hangup
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 217.0.21.165:5060
[Mar 18 13:48:51] DEBUG[5853] channel.c: Didn't get a frame from channel: SIP/DTAG-IP_IN21_165-00000244
[Mar 18 13:48:51] DEBUG[5853] res_rtp_asterisk.c: Setting the marker bit due to a source update
[Mar 18 13:48:51] DEBUG[5853] channel.c: Bridge stops bridging channels SIP/DTAG-IP_IN21_165-00000244 and SIP/1111-00000246

[Mar 18 13:48:51] DEBUG[5853] res_odbc.c: odbc_release_obj2(0x2a61bd8) called (obj->txf = (nil))
[Mar 18 13:48:51] DEBUG[5853] cdr_radius.c: Unable to create RADIUS record. CDR not recorded!
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '2016-03-18 13:47:46'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '"0693000000" <+49693000000>'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'von_telekom'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'SIP/DTAG-IP_IN21_165-00000244'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'SIP/1111-00000246'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'Dial'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'SIP/1000&SIP/1111,30,wW'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '65'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '56'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'ANSWERED'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is 'DOCUMENTATION'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '(null)'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '1458305266.616'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '(null)'
[Mar 18 13:48:51] DEBUG[5853] pbx.c: Function result is '(null)'
[Mar 18 13:48:51] DEBUG[5853] cdr_sqlite3_custom.c: About to log: INSERT INTO cdr (calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test) VALUES ('2016-03-18 13:47:46','"0693000000" <+49693000000>','von_telekom','SIP/DTAG-IP_IN21_165-00000244','SIP/1111-00000246','Dial','SIP/1000&SIP/1111,30,wW','65','56','ANSWERED','DOCUMENTATION','','1458305266.616','','')
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Allocating new SIP dialog for [email protected]:5060 - OPTIONS (No RTP)
[Mar 18 13:48:51] DEBUG[21637] acl.c: For destination '217.0.20.166', our source address is '10.0.0.2'.
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Target address 217.0.20.166:5060 is not local, substituting externaddr
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 87.000.000.28:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.0.20.166:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Destroying SIP dialog [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Allocating new SIP dialog for [email protected]:5060 - OPTIONS (No RTP)
[Mar 18 13:48:51] DEBUG[21637] acl.c: For destination '217.0.20.236', our source address is '10.0.0.2'.
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Target address 217.0.20.236:5060 is not local, substituting externaddr
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 87.000.000.28:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.0.20.236:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Allocating new SIP dialog for [email protected]:5060 - OPTIONS (No RTP)
[Mar 18 13:48:51] DEBUG[21637] acl.c: For destination '217.0.20.234', our source address is '10.0.0.2'.
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Target address 217.0.20.234:5060 is not local, substituting externaddr
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 87.000.000.28:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.0.20.234:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Allocating new SIP dialog for [email protected]:5060 - OPTIONS (No RTP)
[Mar 18 13:48:51] DEBUG[21637] acl.c: For destination '217.0.18.36', our source address is '10.0.0.2'.
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Target address 217.0.18.36:5060 is not local, substituting externaddr
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 87.000.000.28:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.0.18.36:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Destroying SIP dialog [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Destroying SIP dialog [email protected]:5060
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[Mar 18 13:48:51] DEBUG[21637] chan_sip.c: Destroying SIP dialog [email protected]:5060
[Mar 18 13:48:51] DEBUG[5853] res_config_sqlite.c: SQL query: INSERT INTO ast_cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,start,answer,end,duration,billsec,disposition,amaflags,uniqueid) VALUES ('"0693000000" <+49693000000>','+49693000000','0603333333','von_telekom','SIP/DTAG-IP_IN21_165-00000244','SIP/1111-00000246','Dial','SIP/1000&SIP/1111,30,wW','2016-03-18 13:47:46','2016-03-18 13:47:55','2016-03-18 13:48:51','65','56','ANSWERED','DOCUMENTATION','1458305266.616')
[Mar 18 13:48:52] DEBUG[5853] channel.c: Hanging up channel 'SIP/1111-00000246'
[Mar 18 13:48:52] DEBUG[5853] chan_sip.c: Hanging up zombie call. Be scared.
[Mar 18 13:48:52] DEBUG[5853] chan_sip.c: Updating call counter for outgoing call
[Mar 18 13:48:52] DEBUG[5853] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff284059b28'

Irgendwelche Ideen oder Erklärungen was da passiert?
 
Zuletzt bearbeitet:
Hallo vwittich,

ich möchte unterstützen.

Ad hoc kann ich zwar nicht sagen, woher die Telefonieabbrüche kommen, ich unterstütze aber gerne bei der Ursachenfindung.
Zunächst einmal interessiert mich, ob der Leitungsweg schon einmal überprüft worden ist und welche Hardware genau genutzt wird.

Falls noch keine Leitungsmessung gemacht worden ist, leite ich das gerne in die Wege.

Es grüßt herzlich Nadine B. von Telekom hilft
 
Den Session Timer hatte ich bereits neulich auf refuse gestellt hat aber bisher keine Verbesserung gebracht.

Hier meine sip show settings output:

Code:
Global Settings:
----------------
  UDP Bindaddress:        0.0.0.0:5060
  TCP SIP Bindaddress:    Disabled
  TLS SIP Bindaddress:    Disabled
  Videosupport:           No
  Textsupport:            No
  Ignore SDP sess. ver.:  No
  AutoCreate Peer:        No
  Match Auth Username:    No
  Allow unknown access:   No
  Allow subscriptions:    Yes
  Allow overlap dialing:  No
  Allow promisc. redir:   No
  Enable call counters:   No
  SIP domain support:     No
  Realm. auth:            No
  Our auth realm          asterisk
  Use domains as realms:  No
  Call to non-local dom.: Yes
  URI user is phone no:   No
  Always auth rejects:    Yes
  Direct RTP setup:       No
  User Agent:             Asterisk PBX 1.8.10.1~dfsg-1ubuntu1
  SDP Session Name:       Asterisk PBX 1.8.10.1~dfsg-1ubuntu1
  SDP Owner Name:         root
  Reg. context:           (not set)
  Regexten on Qualify:    No
  Legacy userfield parse: No
  Caller ID:              asterisk
  From: Domain:
  Record SIP history:     Off
  Call Events:            Off
  Auth. Failure Events:   Off
  T.38 support:           No
  T.38 EC mode:           Unknown
  T.38 MaxDtgrm:          -1
  SIP realtime:           Disabled
  Qualify Freq :          60000 ms
  Q.850 Reason header:    No
  Store SIP_CAUSE:        No

Network QoS Settings:
---------------------------
  IP ToS SIP:             CS0
  IP ToS RTP audio:       CS0
  IP ToS RTP video:       CS0
  IP ToS RTP text:        CS0
  802.1p CoS SIP:         4
  802.1p CoS RTP audio:   5
  802.1p CoS RTP video:   6
  802.1p CoS RTP text:    5
  Jitterbuffer enabled:   No

Network Settings:
---------------------------
  SIP address remapping:  Enabled using externhost
  Externhost:             xxx.ddns.net
  Externaddr:             87.000.000.28:0
  Externrefresh:          30
  Localnet:               10.0.1.0/255.255.255.0

Global Signalling Settings:
---------------------------
  Codecs:                 0x14e (gsm|ulaw|alaw|slin|g729)
  Codec Order:            alaw:20,ulaw:20,g729:20,gsm:20,slin:20
  Relax DTMF:             No
  RFC2833 Compensation:   No
  Symmetric RTP:          Yes
  Compact SIP headers:    No
  RTP Keepalive:          0 (Disabled)
  RTP Timeout:            0 (Disabled)
  RTP Hold Timeout:       0 (Disabled)
  MWI NOTIFY mime type:   application/simple-message-summary
  DNS SRV lookup:         Yes
  Pedantic SIP support:   Yes
  Reg. min duration       60 secs
  Reg. max duration:      3600 secs
  Reg. default duration:  480 secs
  Outbound reg. timeout:  20 secs
  Outbound reg. attempts: 0
  Notify ringing state:   Yes
    Include CID:          No
  Notify hold state:      Yes
  SIP Transfer mode:      open
  Max Call Bitrate:       384 kbps
  Auto-Framing:           No
  Outb. proxy:            <not set>
[B]  Session Timers:         Refuse[/B]
  Session Refresher:      uas
  Session Expires:        1800 secs
  Session Min-SE:         90 secs
  Timer T1:               500
  Timer T1 minimum:       100
  Timer B:                32000
  No premature media:     Yes
  Max forwards:           70

Default Settings:
-----------------
  Allowed transports:     UDP
  Outbound transport:     UDP
  Context:                sonstige
  Force rport:            Yes
  DTMF:                   rfc2833
  Qualify:                0
  Use ClientCode:         No
  Progress inband:        Never
  Language:               de
  MOH Interpret:          default
  MOH Suggest:
  Voice Mail Extension:   asterisk

----

Und sip show peer telekom

Code:
  * Name       : telekom
  Secret       : <Not set>
  MD5Secret    : <Not set>
  Remote Secret: <Set>
  Context      : von_telekom
  Subscr.Cont. : <Not set>
  Language     : de
  AMA flags    : Unknown
  Transfer mode: open
  CallingPres  : Presentation Allowed, Not Screened
  FromDomain   : tel.t-online.de Port 5060
  Callgroup    :
  Pickupgroup  :
  MOH Suggest  :
  Mailbox      :
  VM Extension : asterisk
  LastMsgsSent : 32767/65535
  Call limit   : 5
  Max forwards : 0
  Dynamic      : No
  Callerid     : "" <>
  MaxCallBR    : 384 kbps
  Expire       : -1
  Insecure     : port,invite
  Force rport  : Yes
  ACL          : No
  DirectMedACL : No
  T.38 support : No
  T.38 EC mode : Unknown
  T.38 MaxDtgrm: -1
  DirectMedia  : No
  PromiscRedir : No
  User=Phone   : No
  Video Support: No
  Text Support : No
  Ign SDP ver  : No
  Trust RPID   : Yes
  Send RPID    : No
  Subscriptions: Yes
  Overlap dial : No
  DTMFmode     : rfc2833
  Timer T1     : 500
  Timer B      : 32000
  ToHost       : tel.t-online.de
  Addr->IP     : 217.0.23.100:5060
  Defaddr->IP  : (null)
  Prim.Transp. : UDP
  Allowed.Trsp : UDP
  Def. Username: [email protected]
  SIP Options  : timer
  Codecs       : 0x14e (gsm|ulaw|alaw|slin|g729)
  Codec Order  : (alaw:20,ulaw:20,g729:20,gsm:20,slin:20)
  Auto-Framing :  No
  Status       : OK (30 ms)
  Useragent    :
  Reg. Contact :
  Qualify Freq : 60000 ms
  Sess-Timers  : Refuse
  Sess-Refresh : uas
  Sess-Expires : 1800 secs
  Min-Sess     : 90 secs
  RTP Engine   : asterisk
  Parkinglot   :
  Use Reason   : No
  Encryption   : No
 
Zuletzt bearbeitet:
Bislang hatte ich dieses Problem noch nie, am vergangenen Wochenende dann plötzlich auch, allerdings mit dazwischen geschalteter Fritzbox.
Abgehend telefoniert über Asterisk->Fritzbox->TCom, nach geschätzt einer Minute war das Gespräch auf einmal weg. Noch mal angerufen, nach nicht mal 10 Sekunden wieder weg. Anderes Telefon genommen, das direkt über eine in Asterisk eingerichtete Nummer (Asterisk->TCom) läuft, ging wunderbar.

Nachdem meine Konfiguration nun seit einem Jahr (von ein paar wenigen Forbidden abgesehen) einwandfrei funktioniert hat, frage ich mich was man bei der TCom neuerdings verschlimmbessert hat :noidea:

Ich habe eine Vermutung, die mir auch schon mit der Forbidden Geschichte durch den Kopf ging. Kann es sein, dass sich Asterisk und Fritzbox sozusagen gegenseitig stören? Die TCom verlangt für abgehende Gespräche ja eine erfolgreiche Registrierung. Fritzbox und Asterisk registrieren sich von unterschiedlichen Ports aus, bringt das den Sicherheitsmechanismus des Servers vielleicht durcheinander?

@Nadine, ein Leitungsfehler oder dergleichen ist seeeehr unwahrscheinlich, da das Gespräch ganz klar seitens Telekom mit einem BYE beendet wurde.

@xrated, direkt über Dir stehen doch seine SIP Settings ;-)
 
In den SIP Settings steht aber doch alles drin, also auch das, was in der sip.conf nicht gesetzt und damit Default ist. :gruebel:
Verglichen mit meiner (funktionierenden) Konfig sehe ich jedenfalls keine wesentlichen Unterschiede.

Ich hatte am Wochenende Zeit und hab mir die Logs von letzter Woche noch mal genau durchgesehen. Bei mir wurde das erste Gespräch deshalb abgebrochen, weil kein SIP OK kam. Für Asterisk war deshalb nach 60 Sek der Dial Timeout abgelaufen und er hat aufgelegt. Im zweiten Fall kam nach 10 Sek ein BUSY. Witziger Weise konnte ich mich in beiden Fällen mit dem Gegenüber unterhalten, wohl dank progress bzw. early audio.

@vwittich, ohne Kontakt zu einem TCom Techniker, der unmittelbar nach einem fehlerhaften Anruf in deren System nachschauen kann was genau passiert ist, wird das wohl schwierig.
 
Ich will eben genau das *nicht* sehen was default ist, ich weiß nicht auswendig was da überall default ist.

Man könnte noch sip debugs einschalten oder mit sngrep schauen was los ist.
 
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.