Das support.log ist ein STARFACE Aktivitätsprotokoll, es beinhaltet alle wichtigen Meldungen für die Telefonie. Es ist wurde mit dem Ziel entwickelt leicht verständlich und sehr gut lesbar zu sein. Der folgende Artikel erklärt den Aufbau, Struktur und erklärt die wichtigsten Log-Einträge.
| Inhalt | ||
|---|---|---|
|
Aufbau des Protokolls
Allgemein
Jeder Logauszug enthält immer folgende Einträge:
| Eintrag | Beschreibung |
|---|---|
[2018-06-21 11:25:44,700] | Exakter Zeitstempel |
[0073] | Individuelle Anruf-ID, beginnt nach Dienste-Neustart bei 0 und zählt fortlaufende die Anrufe Anruf-ID ist einmalig und steht für einen bestimmten Anruf |
********* Call created ********* | Anrufs-Protokollierung gestartet |
********* Call finished ********* | Anruf-Protokollierung beendet |
Eingehender Anruf
Visualisierung des Verbindungsaufbau
Beispiel:
| Kein Format | ||
|---|---|---|
| ||
[2018-06-21 11:25:44,700] [0073] ********* Call created ********* [2018-06-21 11:25:44,700] [0073] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls [2018-06-21 11:25:44,700] [0073] Module "Zeitgesteuerte Umleitung" instance "Öffnungszeiten" has presumably modified the call [2018-06-21 11:25:44,700] [0073] Starting call routing : SIP/100952581997-00000133|1529573144.308 dial number 004972115104222 CallerId +4993961234567 <004993961234567> [2018-06-21 11:25:44,759] [0073] Routing call " <004993961234567>" to number 004972115104222 over service RingAllGroupService [2018-06-21 11:25:44,759] [0073] CallLeg 8af3a07a-ef8a-41b1-9dd6-aa4cf247588f [2018-06-21 11:25:44,953] [0073] Dial SIP/100952581997-00000133 to SIP/16.AL-00000134 [2018-06-21 11:25:44,975] [0073] Dial SIP/100952581997-00000133 to SIP/3168.snomd345-00000136 [2018-06-21 11:25:45,022] [0073] SIP/3168.snomd345-00000136 Channelstate is Ringing [2018-06-21 11:25:45,048] [0073] SIP/16.AL-00000134 Channelstate is Ringing [2018-06-21 11:25:49,531] [0073] SIP/16.AL-00000134 Channelstate is Up [2018-06-21 11:25:49,583] [0073] SIP/3168.snomd345-00000136 Hangup Cause: Answered elsewhere [2018-06-21 11:25:49,659] [0073] SIP/100952581997-00000133 Channelstate is Up [2018-06-21 11:25:49,662] [0073] SIP/16.AL-00000134 Link SIP/100952581997-00000133 [2018-06-21 11:26:29,554] [0073] SIP/16.AL-00000134 HangupRequestEvent [2018-06-21 11:26:29,585] [0073] SIP/16.AL-00000134 Unlink SIP/100952581997-00000133 [2018-06-21 11:26:29,590] [0073] SIP/16.AL-00000134 Hangup Cause: Normal Clearing [2018-06-21 11:26:29,610] [0073] SIP/100952581997-00000133 Hangup Cause: Normal Clearing [2018-06-21 11:26:29,618] [0073] ********* Call finished ********* [2018-06-21 11:26:29,621] [0073] Got dialstatus DialReturnCodes(hc=NORMAL_CLEARING, ds=ANSWER, cr=UNKNOWN) |
...
| Eintrag | Beschreibung |
|---|---|
Relevance check in "XXX" on callstage YYY | Überprüfung eines aktiven Modul ob der Anruf für dieses relevant, XXX wir durch den Modulnamen ersetzt, YYY ist die Ausführungsebene, siehe Module |
Module "Typ" instance "Name" has presumably modified the call | Das Modul Typ XXX mit dem Namen YYY wurde aktiv und hat den Anruf bearbeitet |
Starting call routing : CallLeg dial number XXX CallerId YYY | Anruf wird nun durchgeführt CallLeg ist die interne ID / Sprachkanal des Anrufs, z. B. SIP oder DAHDI / SRX oder local* für intern XXX ist die angerufene Nebenstelle der Anlage YYY CallerID des Anrufs, bei eingehenden die des Anrufers im +49 Format und im internationalen 0049 |
Routing call *Y* to *X* over Service RingAllGroupService | Anruf wird zu einer RingAll-Gruppe verbunden (siehe ServiceTabelle) |
Dial SIP *CallLeg* to SIP/Telefonname | Anrufaufbau des obigen Anruf / CallLeg zu Telefon |
*Telefonname* Channelstate is ringing | Telefon klingelt |
*Telefonname* Channelstate is Up | Anruf wurde angenommen (z. B. Hörer abgenommen) |
*Telefonname* Link *CallLeg* | Sprachverbindung zwischen Anruf und Telefon wurde hergestellt |
*Telefonname* Hangup Cause: Answered elsewhere | Anruf wurde abgebrochen da er von einem anderen Teilnehmer angenommen wurde (z.B. wenn mehrere Telefone klingeln) |
*Telefonname* HangupRequestEvent | Anruf wurde beendet, hier von *Telefonname* da es als erstes steht |
*Telefonname* Unlink *CallLeg* | Sprachverbindung zwischen Anruf und Telefon wird beendet |
*Telefonname* Hangup Cause: Normal Clearing | Anruf wurde einwandfrei ohne Fehler beendet |
********* Call finished ********* | Anruf-Protokollierung beendet |
| Got dialstatus DialReturnCodes (hc=NORMAL_CLEARING, ds=ANSWER, cr=UNKNOWN) | Zusammenfassung des Anrufstatus, *hc = Hangup Cause*, *ds = DialStatus*, *cr= Call Result* |
Ausgehender Anruf
Beispiel:
| Kein Format | ||
|---|---|---|
| ||
[2018-06-21 11:26:42,367] [0074] ********* Call created ********* [2018-06-21 11:26:42,570] [0074] SIP/16.AL-00000139 Channelstate is Ringing [2018-06-21 11:26:43,687] [0074] SIP/16.AL-00000139 Channelstate is Up [2018-06-21 11:26:43,707] [0074] Starting call routing : SIP/16.AL-00000139|1529573202.314 dial number 004993961234567 CallerId click2dial: 004993961234567 <004993961234567> [2018-06-21 11:26:43,779] [0074] Routing call "click2dial: 004993961234567 <004993961234567>" to number 004993961234567 over service OutgoingService [2018-06-21 11:26:43,779] [0074] CallLeg ab26e43e-1611-4c39-82e6-4bfcf7e65147 [2018-06-21 11:26:43,786] [0074] Found lines for LINE routing [2018-06-21 11:26:43,786] [0074] - SIP/100952581997 [2018-06-21 11:26:43,796] [0074] CallerParticipationInfo is set and line.isNoScreening [2018-06-21 11:26:43,796] [0074] CallerParticipationInfo is no CONFERENCEPARTICIPANT [2018-06-21 11:26:43,797] [0074] USER 004972115104222 [2018-06-21 11:26:43,797] [0074] Signal number after calculate 004972115104222 [2018-06-21 11:26:43,797] [0074] SipconnectDisplayNumber +4972115104222 [2018-06-21 11:26:43,798] [0074] Normalized Number +4972115104222 [2018-06-21 11:26:43,798] [0074] Signalling on Line QSC-NGN(3168) [2018-06-21 11:26:43,803] [0074] P-Asserted-Identity <sip:+4972115104222@duro01.sipconnect.qsc.de> sipheader [2018-06-21 11:26:43,807] [0074] CALLERID(all) +4972115104222 <+4972115104222> channel [2018-06-21 11:26:43,827] [0074] Dialing line SIP/100952581997 with extension +4993961234567 [2018-06-21 11:26:43,935] [0074] Dial SIP/16.AL-00000139 to SIP/100952581997-0000013a [2018-06-21 11:26:48,200] [0074] SIP/100952581997-0000013a Channelstate is Ringing [2018-06-21 11:26:51,414] [0074] SIP/100952581997-0000013a Channelstate is Up [2018-06-21 11:26:51,415] [0074] SIP/100952581997-0000013a Link SIP/16.AL-00000139 [2018-06-21 11:27:34,044] [0074] SIP/16.AL-00000139 HangupRequestEvent [2018-06-21 11:27:34,051] [0074] SIP/100952581997-0000013a Unlink SIP/16.AL-00000139 [2018-06-21 11:27:34,053] [0074] SIP/100952581997-0000013a Hangup Cause: Normal Clearing [2018-06-21 11:27:34,058] [0074] Got dialstatus DialReturnCodes(hc=NORMAL_CLEARING, ds=ANSWER, cr=UNKNOWN) [2018-06-21 11:27:34,061] [0074] SIP/16.AL-00000139 Hangup Cause: Normal Clearing [2018-06-21 11:27:34,073] [0074] ********* Call finished ********* |
...
Call Result | Beschreibung |
|---|---|
Unknown | Kein Rückgabewert |
Connected | Anruf wurde angenommen |
Not_Connected | Anruf wurde nicht angenommen |
Module | Anruf wurde von Modul angenommen |
Voicemailbox | Anruf wurde von Voicemailbox angenommen |
Module
Alle Module in der STARFACE haben eine Ausführungsreihenfolge festgelegt.
Admin > Module > Ausführungsreihenfolge:
Eingehender Anruf:
...
| Eintrag | Bedeutung |
|---|---|
Relevance check in "XXX" on callstage YYY | Es gibt ein aktives Modul mit dem Namen XXX in der Ausführungsfolge (callstage) YYY, Der Anruf wird dem Modul übergeben |
Module "Typ" instance "Name" has presumably modified the call | Das Modul von Typ Typ mit dem Namen Name wurde ausgeführt und hat vermutlich in den Anruf eingegriffen Das Modul kann z. B. Parameter des Anruf gelesen haben wie die CallerID |
Module "Name" executes Asterisk Application Command: Progress | Modul mit Name Name greift aktiv den den Anruf ein führt Befehle über den Telefoniedienst aus Progress bedeutet das Modul übernimmt den Anruf, es folgen weitere Befehle |
Module "Name" executes Asterisk Application Command: Playback(silence/1) | Modul mit Name Name spielt eine Sprachnachricht ab, hier Stille, wird z. B. für eine Weitervermittlung benötigt |
Szenarien & Fehlerfälle
Ausgehender Anruf über Tastenfeld
| Kein Format |
|---|
********* Call created ********* Starting call routing : SIP/2996.KXHDV230-00000020|1531227877.34 dial number e3207 CallerId Starface <62> Routing call "Test3 <62>" to number e3207 over service BLFServiceSelector ********* Call created ********* Starting call routing : SIP/2996.KXHDV230-00000022|1531227946.36 dial number a1534 CallerId Starface <62> Routing call "Test3 <62>" to number a1534 over service UserService |
...
| Eintrag | Bedeuteung |
|---|---|
| eXXXX | Interne ID einer Direktwahl |
| aXXXX | Interne ID des angerufenen Benutzers, wird über drücken einer BLF-Taste am Telefon gewählt |
Benutzeranmeldung am Telefon
Logauszug:
| Kein Format |
|---|
********* Call created ********* Starting call routing : SIP/2996.KXHDV230-0000003f|1531321919.66 dial number *7762 CallerId 2996.KXHDV230 <2996.KXHDV230> Routing call "2996.KXHDV230 <2996.KXHDV230>" to number *7762 over service LoginUserAtTelephoneService CallLeg 9a02a021-61e4-4e29-93d8-a9b99cff1378 SIP/2996.KXHDV230-0000003f Channelstate is Up Got dialstatus DialReturnCodes(hc=NORMAL_CLEARING, ds=ANSWER, cr=CONNECTED) SIP/2996.KXHDV230-0000003f Hangup Cause: Normal Clearing ********* Call finished ********* |
...
| Eintrag | Bedeuteung |
|---|---|
dial number *7762 | Der Benutzer mit Login 62 meldet sich durch Wahl von *7762 auf dem Telefon an |
<2996.KXHDV230> | Name es Telefons |
service LoginUserAtTelephoneService | Ziel des Anruf ist der Service LoginUserAtTelephoneService (Benutzeranmeldung) |
Weiterleitungen
Sind Weiterleitungen im Webinterface für eine Rufnummer konfiguiert wird dies im support.log deutlich protokolliert.
CFU (call forward unconditional) / Immerumleitung
| Kein Format |
|---|
********* Call created ********* Routing call " <004972115104222>" to number 004972150995180 over service UserService CallLeg 475295b2-a2ba-4556-baa2-80fafb898833 Forwarding call to 72 ALWAYS Got dialstatus DialReturnCodes(hc=STARFACE_REDIRECTED, ds=NOANSWER, cr=NOT_CONNECTED) Routing call " <004972115104222>" to number 72 over service UserService ... |
...
Umleitung bei Zeitüberschreitung / CFNR (call forward no reply)
| Kein Format |
|---|
Routing call " <004972115104222>" to number 004972150995180 over service UserService CallLeg 6860d911-6ea1-47df-afa4-57b767ba6d78 Dial SIP/c854437095d98196f80990eb01f1568367a62f5a-00000069 to SIP/2996.KXHDV230-0000006a SIP/2996.KXHDV230-0000006a Channelstate is Ringing SIP/2996.KXHDV230-0000006a Hangup Cause: Normal Clearing Got dialstatus DialReturnCodes(hc=NO_ANSWER, ds=NOANSWER, cr=UNKNOWN) Forwarding call to 72 TIMEOUT Routing call " <004972115104222>" to number 72 over service UserService ... |
...
| Eintrag | Bedeuteung |
|---|---|
Forward to deposit 72. Original number = 59995186 | Eingehender Anruf auf der Rufnummer 59995186 wurde abgeworfen |
Forwarding call to 72 ALWAYS | Anruf wird auf die 72 weitergeleitet |
Routing call XXX to number 38 over service PluginSelectorService | Start des Anrufaufbaus zum Weiterleitungsziel |
Pick eingehender Anruf
Hier ist es wichtig auf die einzelnen Anrufe anhand deren ID zu achtent, im folgenden Log-Auszug ist der Pick-Anruf farblich markiert.
...
Der Pick ist hier erfolgreich, der Pick-Anruf wird mit dem Hangup Cause STARFACE_REDIRECTED beendet.
DTMF (dual-tone multi-frequency) Töne
Auszug support.log bei eingehenden und ausgehenden Anrufen:
...
DMTF sind im support.log nicht eindeutig erkennbar, hier muss das pbx.log zur weiteren Analyse ausgewertet werden.
Ausgehender Anruf - Keine Leitung gefunden
Auszug Log:
| Kein Format |
|---|
********* Call created ********* Starting call routing : SIP/1007.ylnkt46s-0000001c|1531295449.34 dial number 01749876543 CallerId STARFACE Test <10> Routing call "STARFACE Test <10>" to number 01749876543over service OutgoingService CallLeg 99bba061-eb4e-4cbc-b906-3a928d688fca No line found routing: COR rejecting call Got dialstatus DialReturnCodes(hc=CALL_REJECTED, ds=UNKNOWN, cr=UNKNOWN) SIP/1007.ylnkt46s-0000001c Hangup Cause: Call Rejected ********* Call finished ********* |
...