Einrichtungsleitfaden um ein OpenStage an der Fritzbox anzumelden

Idealist · 9 Feb 2012

Hallo zusammen,
habe eine FB 7270 V3, die ich bisher nicht für Telefonie genutzt habe. Habe noch keine Erfahrung mit VoIP und habe das Ding am DSL mit ISDN Mehrgeräteanschluß. Möchte künftig gerne FritzFon MT-F als Mobilteile nutzen, habe noch vorhandene DECT Gigaset Mobilteile und ein analoges Faxkombi. Nun brauche noch ein komfortables Tischtelefon, das ich mit meinem Outlook (ca. 2000 Kontakte) synchronisieren möchte.
Würde aber am liebsten ein OS 80 G nehmen, alternativ denke ich über ein Gigaset DX800A oder ein DE900 IP nach.
Bevor ich jetzt bestelle frage ich zur Sicherheit noch mal nach:
1) Meine FB hat aktuelle firmware. Muss ich hier zum Betrieb des OS 80 eine spezielle firmware installieren?
2) Kann ich einfach ein aktuelles OS 80 G kaufen oder benötige ich hier spezielle SW/firmware?
3) Gibt es Probleme beim Verbinden/Vermitteln von Gesprächen vom OS zu anderen Telefonen an der FB?
4) Werden Nachrichten auf dem AB der FB am OS signalisiert (hab ich aus dem bisherigen Thread heraus nicht genau verstanden)?
5) Wird auf dem OS angezeigt, wenn andere Teilnehmer an der FB telefonieren?
6) Ist die Anzahl der Kontakte im OS limitiert?
7) Kann ich mit dem AVM Tapi Treiber aus Outlook wählen und via FB das OS als Endgerät für TAPI konfigurieren?
8) Welchen Vorteil hätte ich, wenn ich noch eine kleine HiPath kaufen würde?
Welche?
Vielen Dank für alle Tipps!

trendchiller · 10 Feb 2012

Fragen über Fragen...

ich fasse mich mal kurz und antworte besten Wissens und Gewissens:

1) normals Firmware reicht, die kann SIP, dafür muss das OS eine SIP-Firmware haben. Kaufe Dir doch aber lieber ein OS80 anstatt eines G, denn der einzige Unterschied ist das Gb Interface, was Du zum telefonieren nict brauchst, es sei denn, Du willst nen Rechner dahinter hängen...
2) das OS80 benötigt eine spezielle SIP-Firmware, die offiziell nicht zur Verfügung gestellt werden darf, ausser über Händler oder SEBA (Siemens)
3) Vermittlungsprobeme, etc gibt es nicht MEHR mit aktuellen FWs
4) Die MWI Finktion funktioniert bei korrekter Konfiguration des OS80 mit aktueller Labor-FW
5) Nein
6) gute Frage, aber ich habe mein komplettes Outlook drin (per OS-MAnager) und das ist einiges...
7) SIP-Telefone werden dafür derzeit nicht unterstützt...

eine kleine HiPath... kostet mehr Geld, aber Du kannst HFA nutzen und die Anlagenfunktionalität, hast aber weniger SoHo Features, wie AB, Fax, etc... die in der FB drin sind.

ABER:
die aktellen Labors haben ein Problem mit der SIP-Telefonie und OS80 Geräten (und wohl auch SNOM, etc...)
Diese äußern sich im NICHTunterstützn von G.722 (verjitterte GEspräche und Klingeln) und eingehende Anrufe werden zwar signalisiert, aber es funktioniert keine Sprache (ausgehend funktioniert) (scheint ein RTP-Problem zu sein...)
Wir hoffen alle, daß AVM das behebt...

ich hoffe, das hilft ein wenig ?

Idealist · 10 Feb 2012

Schon mal vielen Dank für die Infos!
Habe offenbar wenig Ahnung von den Möglichkeiten aktueller Telefonie - echt spannend!
1) & 4) Also für die FB reicht die aktuelle Firmware, für "MWI" (scheint das Anzeigen von Nachrichten auf dem AB zu bezeichnen) bräuchte ich aber die aktuelle LaborSW (Welche? Hab ich noch keine Ahnung von!). Die hat aber dann wieder ein Problem mit SIP-Telefonie und OS 80?
2) Welche spezielle SIP-Firmware braucht denn das OS80 und wo soll ich versuchen, die herzubekommen? (Hatte in anderen Threads gelesen, dass die aktuelle Firmware des OS80 ausreichend sei!?)
Bei der Frage, was von meinen Anforderungen die HiPath nun mehr kann, reduziert sich das offenbar auf die Anzeige, welcher TN gerade telefoniert und das Wählen aus einer PC-Applikation via TAPI mit dem OS 80?!
Zur Sicherheit vielleicht einfach mal eine Auflistung was ich will:
a) 2-3 Leitungen nach draußen (aktuell mit ISDN Mehrgeräteanschluß)
b) 1-2 schöne Tischtelefone mit Möglichkeit für schnurloses Headset und Synchronisation der OutlookKontakte, damit PC nicht immer an sein muss (möglich mit OS 80 an FB 7270)
c) Analoges Kombifax (vorhanden) anschließbar (möglich mit FB 7270)
d) Mobilteile anschließbar (möglich mit FB 7270)
e) Gespräche von Extern an andere Internteilnehmer verbinden/weitergeben, oder auch zwischen Internteilnehmern. (Mit FB und OS 80 offenbar möglich).
e) Voice to mail (möglich mit FB 7270), schön wäre ein zeitgesteuertes AB-Profil mit 2-3 verschiedenen Ansagen, kann aber auch ohne leben.
f) Fax to mail (möglich mit FB 7270)
g) Telefonieren aus PC Applikation via TAPI 2.x über ein jeweils konfiguriertes Endgerät (lt. Trendchiller mit OS80 an FB derzeit nicht möglich, hatte ich aus den bisherigen Chats und aus der Siemens-HP anders heraus gelesen)
Bei HiPath für OS scheint die kleinste Lösung eine HP540 zu sein, die aber offenbar nicht so zukunftssicher wäre, da keine Ausbaumöglichkeiten. Die nächst-kleinere ausbaufähige ist dann wohl die HP 3350.
Wenn ich mir das dann so zusammen rechne, frage ich mich, inwieweit nicht eine externe TK-Lösung via VoIP-Anbieter wie Sipgate oder Placetel eine Alternative sein könnte!? Damit müßte dann alles gehen!

Idealist · 10 Feb 2012

Habe noch eine weitere Frage, die ich jetzt hier poste, obwohl sie nicht 100% zu diesem Thema passt, kann aber offenbar keine neues Thema erstellen:
Kann mir jemand sagen, ob es einen Unterschied zwischen einem OS 80 G mit der Hersteller Nr 30250-F600-C119 und einem =S 80 G mit der Hersteller Nr 30250-F600-C114 gibt?
Sorry für die vielen Fragen!
Gruß
Carsten

KK-SIP · 11 Feb 2012

Idealist schrieb:
Kann mir jemand sagen, ob es einen Unterschied zwischen einem OS 80 G mit der Hersteller Nr 30250-F600-C119 und einem =S 80 G mit der Hersteller Nr 30250-F600-C114 gibt?

Das OpenStage 80G mit der Nummer L30250-F600-C119 hat eine propietäre HFA-Firmware, d.h. dieses Telefon ist für z.B. HiPath 2000 / 3000 / 4000 Systeme.
Das OpenStage 80G mit der Nummer L30250-F600-C114 hat eine SIP-Firmware, d.h. dieses Telefon ist vorwiegend für OpenScape Voice gedacht, funktioniert aber dank SIP z.B. auch an der FritzBox!

Idealist · 11 Feb 2012

Das Telefon mit der -C119 wurde mir jedoch verkauft als "Siemens OpenStage 80 G - VoIP-Telefon - SIP - Blue Silver".
Ist gerade angekommen - Du hast Recht auf dem Typenschild steht !OpenStage 80 G HFA"
Kann ich die Firmware ändern und es damit zum SIP-Telefon machen? Woher bekomme ich die Firmware?
Gruß
Carsten

KK-SIP · 13 Feb 2012

Idealist schrieb:
Das Telefon mit der -C119 wurde mir jedoch verkauft als "Siemens OpenStage 80 G - VoIP-Telefon - SIP - Blue Silver".
Ist gerade angekommen - Du hast Recht auf dem Typenschild steht !OpenStage 80 G HFA"

Vielleicht hat da ja schon jemand eine SIP-Firmware eingespielt und deshalb das OpenStage als "SIP" angeboten. Am besten mal das Telefon einschalten und checken...

Idealist schrieb:
Kann ich die Firmware ändern und es damit zum SIP-Telefon machen?

Ja, die Firmware kann von SIP zu HFA und umgekehrt geändert werden.

Idealist schrieb:
Woher bekomme ich die Firmware?

Einfach mal in diesem Forum suchen, in einigen Beiträgen werden entsprechende Bezugsquellen genannt.

Idealist · 13 Feb 2012

Um es nicht unnötig kompliziert zuu machen, hab ich das Gerät jetzt reklamiert und bekomme es getauscht gegen die SIP-Variante.
Da Du ja auch ein OS 80 an einer FBF hast:
- Arbeitest Du mit der aktuellen Labor-FW auf der FBF und werden damit Nachrichten auf dem AB am OS 80 signalisiert?
- Falls ja, gibt es ansonsten Probleme durch die Labor-FW gegenüber der regulären aktuellen FW?
- Kannst Du bestätigen, dass mit dem AVM Tapi Treiber aus Outlook wählen und via FB das OS als Endgerät für TAPI konfigurieren nicht funktioniert und das gerenerell mit SIP-Telefonen derzeit nicht unterstützt wird?

KK-SIP · 14 Feb 2012

Idealist schrieb:
- Arbeitest Du mit der aktuellen Labor-FW auf der FBF und werden damit Nachrichten auf dem AB am OS 80 signalisiert?
- Falls ja, gibt es ansonsten Probleme durch die Labor-FW gegenüber der regulären aktuellen FW?

Ich verwende keine Labor-FW, sondern das aktuelle Release, daher kann ich dazu nichts sagen.

Idealist schrieb:
- Kannst Du bestätigen, dass mit dem AVM Tapi Treiber aus Outlook wählen und via FB das OS als Endgerät für TAPI konfigurieren nicht funktioniert und das gerenerell mit SIP-Telefonen derzeit nicht unterstützt wird?

Das Wählen über den AVM Tapi Treiber (oder einen anderen Tapi-Treiber) funktioniert definitiv nicht. Auch die CallBridge Collection von Siemens funktioniert nur mit den TDM- bzw. HFA-Varianten und nicht mit der SIP-Variante.

Idealist · 14 Feb 2012

Danke

, dann brauch ich das erst gar nicht lange propbieren!

com-com · 4 Aug 2012

habe ähnliche Konfiguration seit einen halben Jahr und wollte jetzt noch die TAPI von der Fritzbox in Betrieb nehmen. Ankommend geht die AVM-Tapi. Abgehend weder über Dialer.exe noch über andere TAPI-Programme wie z.B. Smart Call Monitor.
Da "pfuscht" AVM immer noch ... !

vice_pres · 7 Jan 2014

Hi,

Ich habe mir ein Openstage 60 und ein Openstage 80 ausgeliehen und mit der passenden aktuellen SIP Firmware versorgt (V3_R1_49_0). Einrichtung muss ich nachher noch machen, hab mir aber diverse Threads hier schonmal durchgelesen. MWI funktioniert ja mittlerweile und im anderen Thread gibts ja auch Tips für die Leitungstasten.

Eine Frage bleibt aber: Funktioniert der Zugriff aufs Fritzbox Telefonbuch vielleicht mittlerweile? Evtl. per XML App oder so? Die ersten Beiträge dazu sind ja deutlich älter und auch zu MWI hieß es ja, dass es nicht geht.

Vielen Dank schonmal!

Gruß
Peter

Edith sagt:
MWI will leider nicht so wirklich bei mir. MWI Server ist eingetragen als fritz.box oder als IP. Leider passiert nix, vermute hier aber den Fehler bei der Fritzbox leider... Im Trace kommt ne Meldung, dass MWI Subscribe ok wäre, dann kommt auch mal eine Antwort der Fritzbox, dass keine Message da wäre. Bei einer neuen AB Nachricht kommt aber kein Notify und bei einem reboot kommt immer noch kein message wartend. Die Frage ist aber: Wie teile ich dem OS mit, dass er den ersten AB mit MWI abfragen soll? Ich hab zwar auch nur einen, aber es könnte ja mehrere geben. Muss man da noch was einstellen?

Code:

___TRACE:___   Tue Jan  7 18:36:27 2014.576
SIP Signalling(2588): ./src/sg_engine_sub.cpp:171 SendSubscribeA_New() - unHandle(5001) unCallId(1) rstrEvent(message-summary) rstrEventsPackage() pEventParameter(0x962a1c,0) unTimeToLive(3600) unLineID(1) eSubscriptionType(12)

___TRACE:___   Tue Jan  7 18:36:27 2014.576
SIP Signalling(2588): ./src/sg_engine_sub.cpp:309 Send SUBSCRIBE using MwiSvc - eSubscriptionType(12) IsSubscriptionActive(0)

___TRACE:___   Tue Jan  7 18:36:27 2014.577
SIP Signalling(2588): ./src/sg_engine_sub.cpp:328 Subscribe (MwiSvc)

___TRACE:___   Tue Jan  7 18:36:27 2014.586
SIP Messages(2589): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
SUBSCRIBE sip:[email protected]:5060;transport=udp SIP/2.0
Accept: application/simple-message-summary
Via: SIP/2.0/UDP 192.168.3.18;branch=z9hG4bKe8fb9f4a7b3fd56a8
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=79fba49a3c
To: <sip:[email protected]>
Call-ID: 35a4aa28247088b5
CSeq: 518374608 SUBSCRIBE
Allow: NOTIFY
Contact: "621" <sip:[email protected]:5060;transport=udp>
Event: message-summary
Expires: 3600
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Tue Jan  7 18:36:27 2014.592
SIP Messages(2589): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.3.18;branch=z9hG4bKe8fb9f4a7b3fd56a8
From: "621" <sip:[email protected]>;tag=79fba49a3c
To: <sip:[email protected]>;tag=C62EE0D7C0737E20
Call-ID: 35a4aa28247088b5
CSeq: 518374608 SUBSCRIBE
WWW-Authenticate: Digest realm="fritz.box", nonce="9086FF2928776AE1"
User-Agent: FRITZ!OS
Content-Length: 0



___TRACE:___   Tue Jan  7 18:36:27 2014.593
SIP Signalling(2588): ./src/sg_engine_auth.cpp:72 EvCredentialsRequired()

___TRACE:___   Tue Jan  7 18:36:27 2014.593
SIP Signalling(2588): ./src/sg_engine_auth.cpp:127 EvCredentialsRequired() - LineId(1) CallRefId(1)

___TRACE:___   Tue Jan  7 18:36:27 2014.596
SIP Signalling(2588): ./src/sg_engine_auth.cpp:240 EvCredentialsRequired() - request reissued

___TRACE:___   Tue Jan  7 18:36:27 2014.598
SIP Signalling(2589): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(401) Method(SUBSCRIBE)

___TRACE:___   Tue Jan  7 18:36:27 2014.604
SIP Messages(2589): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
SUBSCRIBE sip:[email protected]:5060;transport=udp SIP/2.0
Accept: application/simple-message-summary
Via: SIP/2.0/UDP 192.168.3.18;branch=z9hG4bK29c37c8e10dc1038a
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=79fba49a3c
To: <sip:[email protected]>
Call-ID: 35a4aa28247088b5
CSeq: 518374609 SUBSCRIBE
Allow: NOTIFY
Authorization: Digest username="621",realm="fritz.box",nonce="9086FF2928776AE1",uri="sip:[email protected]:5060;transport=udp",response="b7cca1dd10c056a2148000f8924d512f"
Contact: "621" <sip:[email protected]:5060;transport=udp>
Event: message-summary
Expires: 3600
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Tue Jan  7 18:36:27 2014.611
SIP Messages(2589): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.3.18;branch=z9hG4bK29c37c8e10dc1038a
From: "621" <sip:[email protected]>;tag=79fba49a3c
To: <sip:[email protected]>;tag=65EC583E87CF78B6
Call-ID: 35a4aa28247088b5
CSeq: 518374609 SUBSCRIBE
Expires: 3600
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Content-Length: 0



___TRACE:___   Tue Jan  7 18:36:27 2014.612
SIP Signalling(2588): ./src/sg_engine_sub.cpp:1293 EvSuccess(ISipMwiSvc)

___TRACE:___   Tue Jan  7 18:36:27 2014.612
SIP Signalling(2588): ./src/sg_engine_sub.cpp:1184 EvProgress(ISipMwiSvc)

___TRACE:___   Tue Jan  7 18:36:27 2014.613
SIP Signalling(2588): ./src/sg_events.cpp:236 STK->SIG MsgWaitingNotfiy: Cref=1, Line ID=1:

~~~DEBUG:~~~   Tue Jan  7 18:36:27 2014.613
SIP Signalling(2588): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Tue Jan  7 18:36:27 2014.614
SIP Signalling(2330): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 107; state = 2

___TRACE:___   Tue Jan  7 18:36:27 2014.614
SIP Signalling(2330): ./src/sgCDispatcher.cpp:481 SIG->SIG:  sipMWINotify:

___TRACE:___   Tue Jan  7 18:36:27 2014.614
SIP Signalling(2330): ./src/sg_events_service.cpp:1902 sgEvent_MwiNotification

___TRACE:___   Tue Jan  7 18:36:27 2014.614
SIP Signalling(2330): ./src/sg_mwi_service.cpp:235 SIG->CC sgMWINotification: Subscribe OK

~~~DEBUG:~~~   Tue Jan  7 18:36:27 2014.614
SIP Signalling(2330): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Tue Jan  7 18:36:27 2014.614
SIP Call Control(2330): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 52

___TRACE:___   Tue Jan  7 18:36:27 2014.615
SIP Call Control(2330): ./src/cc_sig_events.c:1181 MWI-Interrogation Complete Message, reason (0) : 0

___TRACE:___   Tue Jan  7 18:36:27 2014.615
SIP Signalling(2589): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(SUBSCRIBE)

___TRACE:___   Tue Jan  7 18:36:27 2014.618
SIP Messages(2589): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
NOTIFY sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bKE6A4C73F3E817356
From: <sip:[email protected]>;tag=65EC583E87CF78B6
To: "621" <sip:[email protected]>;tag=79fba49a3c
Call-ID: 35a4aa28247088b5
CSeq: 518374610 NOTIFY
Event: message-summary
Subscription-State: active;expires=3600
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Content-Type: application/simple-message-summary
Content-Length: 22

Messages-Waiting: no


___TRACE:___   Tue Jan  7 18:36:27 2014.618
SIP Signalling(2588): ./src/sg_engine.cpp:4391 EvOnPacketReceived(NOTIFY)

___TRACE:___   Tue Jan  7 18:36:27 2014.620
SIP Signalling(2588): ./src/sg_engine_sub.cpp:1093 EvNotified(ISipMwiSvc)

___TRACE:___   Tue Jan  7 18:36:27 2014.621
SIP Signalling(2588): ./src/sg_events.cpp:236 STK->SIG MsgWaitingNotfiy: Cref=1, Line ID=1:

~~~DEBUG:~~~   Tue Jan  7 18:36:27 2014.621
SIP Signalling(2588): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Tue Jan  7 18:36:27 2014.622
SIP Signalling(2330): ./src/sgCDispatcher.cpp:481 SIG->SIG:  sipMWINotify:

___TRACE:___   Tue Jan  7 18:36:27 2014.622
SIP Signalling(2330): ./src/sg_events_service.cpp:1902 sgEvent_MwiNotification

~~~DEBUG:~~~   Tue Jan  7 18:36:27 2014.623
SIP Signalling(2330): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Tue Jan  7 18:36:27 2014.623
SIP Call Control(2330): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 51

___TRACE:___   Tue Jan  7 18:36:27 2014.623
SIP Call Control(2330): ./src/cc_sig_events.c:1147 MWI-Deactivate Message, Basic Service (0) : 0

___TRACE:___   Tue Jan  7 18:36:27 2014.624
SIP Signalling(2330): ./src/sg_mwi_service.cpp:202 SIG->CC sgMWINotification: Messages-Waiting: no

Edith II:
Und ich suche noch nach der Möglichkeit die **610 **611 auf eine der Sensortasten zu legen zum verbinden. Zum direkten Anrufen hab ich, das ist ja klein Problem, das direkte Verbinden klappt aber nicht. Bzw das Verbinden generell zickt irgendwie. Ich habe die Möglichkeit zu sagen "Übergabe vor melden" - das akzeptiert aber keine **610. Rückfrage bringt mir das Gespräche ans OS zurück wenn das MT-F (**610) auflegt. Einzige Möglichkeit bisher: Konferenz starten und dann am OS auflegen. Ist natürlich unpraktisch wenn das MT-F in nem anderen Raum liegt und man hin und her laufen muss...

dennis.fischer · 7 Jan 2014

vice_pres schrieb:
Edith II:
Und ich suche noch nach der Möglichkeit die **610 **611 auf eine der Sensortasten zu legen zum verbinden. Zum direkten Anrufen hab ich, das ist ja klein Problem, das direkte Verbinden klappt aber nicht. Bzw das Verbinden generell zickt irgendwie. Ich habe die Möglichkeit zu sagen "Übergabe vor melden" - das akzeptiert aber keine **610. Rückfrage bringt mir das Gespräche ans OS zurück wenn das MT-F (**610) auflegt. Einzige Möglichkeit bisher: Konferenz starten und dann am OS auflegen. Ist natürlich unpraktisch wenn das MT-F in nem anderen Raum liegt und man hin und her laufen muss...

Ich habe die Zieltastem für die internen und externen Teilnehmer mit der Funktion "Repertory dial" bei den Zieltasten gelegt.
Der String sieht dann wie folgt aus: ~**610>

Dadurch wird ein aktives Gespräch automatisch ins Halten gelegt und der angewählte Teilnehmer (in diesem Beispiel **610) angerufen.

Gruß
Dennis

vice_pres · 7 Jan 2014

Hi Dennis,

Das hatte ich heute auch probiert. Aber damit hatte ich das gleiche Problem: wenn das DECT auflegt - ohne, dass der Gesprächspartner auflegt - geht das Gespräch zum OS zurück. Wie überträgst du das Gespräch genau? Du telefonierst und drückst am OS einfach auf die Taste und legst den Hörer auf, gehst zum DECT und nimmst ab und fertig?

Viele Grüße
Peter

LastRaven · 8 Jan 2014

hab hier mal eine kleine Anleitung zusammengeklimpert, wo u.a. auch das Thema MWI beschrieben ist.

vice_pres · 8 Jan 2014

Cool, da hätte ich mir das lesen in verschiedenen Threads ja auch sparen können gestern wenns die schon gegeben hätte

Bzgl MWI bei mir: Ich vermute es gab ein Problem weil ich dem Fritz AB 2 Rufnummern zugewiesen hatte (und die Hauptnummer aufgrund der Sortierung die 2 Nummer des AB war) - ich werde heute Abend mal testen ob MWI jetzt funktioniert.

Bleibt nur noch die Frage wie das mit dem verbinden ist. Ich habe einfach keine "normale" Übergabe zur Auswahl gehabt gestern. Ich hatte mit der Erweiterten Zielwahl schon rumgespielt, aber interessant wäre ob jemand auch eine/mehrere "Verbinden direkt an **6??" Tasten hat und wie die Funktion dort ist.

Ok, das mit dem Telefonbuch hab ich jetzt mal geknickt. Schade, dachte das es da mit XML App oder so ne Möglichkeit gäbe, sonst muss ich mich nochmal mit dem Thema OpenLDAP auseinandersetzen das es hier im Forum vor Jahren schonmal gab.

An meinem OS80 das schon länger im Schrank stand ist jetzt allerdings die Kathode fürs Display ausgefallen. Zuerst gabs noch Falschfarben, mittlerweile aber garnix mehr. Hab mir jetzt mal ein OS60 bestellt, den Preisunterschied zum OS80 hatte ich garnicht mehr so im Kopf und hat mich doch davon abgehalten ein 80er zu nehmen...

Edith sagt:
Also mit dem Repertory dial habe ich genau das Verhalten. Wenn **610 dann auflegt kommt der Anruf zum OS zurück. Ist natürlich etwas unpraktisch. Ausserdem flimmert/blinkt die LED der Leitung auf der der Anruf reinkam nach dem verbinden weiter bis ich die LED-Taste anwähle oder ein neuer Anruf kommt.

MWI funktioniert jetzt nachdem ich dem AB auf der Fritz nur eine Nummer zugewiesen habe.

Edith II:
Hab gerade mal die 35er FW auf dem OS eingespielt. Da kommt auch die Meldung, dass die Übergabe fehlgeschlagen wäre - obwohl das Telefonat auf dem MT-F landet... Manchmal kommt es auch nicht. Egal ob ich die Übergabe abschließe bevor das MT-F rangeht oder nicht. Wenn der Anrufer nicht auflegt dann kommts zum OS zurück.

Edith III:
Da das Gespräch nicht nur zum OS zurückkommt sondern auch am ISDN Bus signalisiert wird (habe ich gerade mal getestet) scheint da irgendwo in der Fritzbox mit dem OS was mächtig verquer zu laufen. Anbei ein Trace vom OS

Code:

___TRACE:___   Thu Jan  9 17:10:17 2014.480
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bKB286FD255FD4497D
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600
Call-ID: [email protected]
CSeq: 14 INVITE
Contact: <sip:[email protected]>
Max-Forwards: 70
Expires: 120
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 361

v=0
o=user 12347479 12347479 IN IP4 192.168.3.254
s=call
c=IN IP4 192.168.3.254
t=0 0
m=audio 7078 RTP/AVP 8 0 2 102 100 99 97 101
a=sendrecv
a=rtpmap:2 G726-32/8000
a=rtpmap:102 G726-32/8000
a=rtpmap:100 G726-40/8000
a=rtpmap:99 G726-24/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:7079


___TRACE:___   Thu Jan  9 17:10:17 2014.480
SIP Signalling(2696): ./src/sg_engine.cpp:9186 EvPacketReceived(ISipTransportObserver) - REQ Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:17 2014.481
SIP Signalling(2695): ./src/sg_engine.cpp:4391 EvOnPacketReceived(INVITE)

___TRACE:___   Thu Jan  9 17:10:17 2014.482
SIP Signalling(2695): ./src/sg_engine.cpp:4413 packet does not match an existing dialog

___TRACE:___   Thu Jan  9 17:10:17 2014.483
SIP Signalling(2695): ./src/sg_engine.cpp:4429 EvOnPacketReceived IsMWI(0) IsCheckSync(0) IsServerStatusHeader(0) IsUACSTARequestContent(0) IsDLSContactMeRequestContent(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.483
SIP Signalling(2695): ./src/sgCallInfoMgmt.cpp:58 CreateCallInfo() - new CallInfo(1511) created, 1 CallInfo(s) in total

___TRACE:___   Thu Jan  9 17:10:17 2014.483
SIP Signalling(2695): ./src/sg_engine.cpp:9947 unIndex(0) unLineIDtmp(1) m_pRoute->GetAvailability(1) mustBeRegistered(1) registered(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.483
SIP Signalling(2695): ./src/sg_engine.cpp:11815 CreateSipContext() - CallInfo created, lineId(1), callRefId(1511)

___TRACE:___   Thu Jan  9 17:10:17 2014.484
SIP Signalling(2695): ./src/sg_engine.cpp:10263 AddUserAgentSvc() - IPv4(192.168.3.162)(1) IPv6(NULL)(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.485
SIP Signalling(2695): ./src/sg_engine.cpp:11870 CreateSipContext() - LineId(1) TLS(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.485
SIP Signalling(2695): ./src/sg_engine_auth.cpp:40 AddDigestClientAuthSvc()  SetLoopThreshold(3)

___TRACE:___   Thu Jan  9 17:10:17 2014.486
SIP Signalling(2695): ./src/sg_engine_rel.cpp:26 AddReliableProvisionalResponseSvc()

___TRACE:___   Thu Jan  9 17:10:17 2014.489
SIP Signalling(2695): ./src/sg_engine.cpp:5172 EvInvited(ISipSessionSvc)

___TRACE:___   Thu Jan  9 17:10:17 2014.489
SIP Signalling(2695): ./src/sg_engine_rel.cpp:72 IsReliableProvisionalResponseRequired() - bRequired(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.489
SIP Signalling(2695): ./src/sg_engine.cpp:5334 EvInvited(ISipSessionSvc) - NewInviteReceivedE(1511, 1, 0, 0xa7d868)

~~~DEBUG:~~~   Thu Jan  9 17:10:17 2014.489
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:17 2014.490
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 114; state = 2

___TRACE:___   Thu Jan  9 17:10:17 2014.490
SIP Signalling(2492): ./src/sgCDispatcher.cpp:145 Engine->CallService: sipCallNewINVITE

___TRACE:___   Thu Jan  9 17:10:17 2014.490
SIP Signalling(2492): ./src/sg_init_service.cpp:109 SetStackTime

---INFO:---    Thu Jan  9 17:10:17 2014.490
SIP Signalling(2492): ./src/sg_init_service.cpp:121 GMT: 2014 Jan 09 16:10:17 Thu

---INFO:---    Thu Jan  9 17:10:17 2014.491
SIP Signalling(2492): ./src/sg_init_service.cpp:129 UTC: y(114) m(0) d(9) h(16) m(10) s(17)

___TRACE:___   Thu Jan  9 17:10:17 2014.491
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:76 CreateCallRecord() - new CallRecord(1511,0) created, 1 CallRecord(s) in total

___TRACE:___   Thu Jan  9 17:10:17 2014.491
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:230 sgEvtCallNewINVITE() - resTraceId(-2147483636,FE:Element not found) resSI_TRUE(1) bStartPrefix(0) strTraceId()

___TRACE:___   Thu Jan  9 17:10:17 2014.491
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(1, 5, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:17 2014.492
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:248 sgEvtCallNewINVITE() - CallRefId(1511) LineId(1) replacedCallRefId(0) packet(0xa7d868)


___TRACE:___   Thu Jan  9 17:10:17 2014.492
SIP Signalling(2492): ./src/sgCDatabaseIf.cpp:800 GetVideoCapability() - eVideoCapability(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.492
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:756 KW: eRtpMediaType(0) bHasRtp(0) bHasSrtpMikey(0) bHasSrtpSdes(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.509
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:168 sgCreateSession() - getLocalSessionDescription: <media-sessiondescription><ip-addr>192.168.3.162</ip-addr><port>5006</port><local-socket><ip-addr-v4>192.168.3.162</ip-addr-v4><port>5006</port><rtcp-port>5007</rtcp-port></local-socket><dtmf-payload>101</dtmf-payload><ptime>0</ptime><max-ptime>60</max-ptime><audio><codec>PCMA</codec><codec>PCMU</codec><codec>G729</codec></audio><ssrc>474266387</ssrc></media-sessiondescription>

___TRACE:___   Thu Jan  9 17:10:17 2014.511
SIP Signalling(2492): ./src/sgCRtpSession.cpp:669 AddSocket RtpSession(0xac2a90) ip(192.168.3.162) port(5006) rtcpport(5007) eIPV4

___TRACE:___   Thu Jan  9 17:10:17 2014.513
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:186 sgCreateSession() - pRtpSession(ac2a90) SessionHandle(19) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.513
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:782 GenerateRtpSessionsHelper() - New RtpSession created, pRtpSession(0xac2a90)

___TRACE:___   Thu Jan  9 17:10:17 2014.514
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:831 GenerateRtpSessionsHelper() - created a vector(0xada090) with 1 RtpSession(s) eRtpMediaType(0) total(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.514
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:720 GenerateRtpSessions() - created a vector(0xada090) with 1 RtpSession(s)

___TRACE:___   Thu Jan  9 17:10:17 2014.515
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:224 SetRtpSessions() - replacing m_pvRtpSessions((nil)) with pRtpSession(0xada090)

___TRACE:___   Thu Jan  9 17:10:17 2014.515
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(0,SDP_NULL) eTypeOfMessage(1) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.516
SIP Signalling(2492): ./src/sgCSipPacket.cpp:999 HasContentTypeHeader() - Content(application/sdp) available

___TRACE:___   Thu Jan  9 17:10:17 2014.516
SIP Signalling(2492): ./src/sgCSipPacket.cpp:918 GetFirstPayload() - application/sdp found

___TRACE:___   Thu Jan  9 17:10:17 2014.517
SIP Signalling(2492): ./src/sgSdpService.cpp:2389 GenerateRemoteCapsMgr() - CallRefId(1511) CallRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:17 2014.519
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:372 SetRemoteSdpOrigin() - m_bRemoteSdpOriginChanged set

___TRACE:___   Thu Jan  9 17:10:17 2014.521
SIP Signalling(2492): ./src/sgSdpService.cpp:1814 GenerateLocalCapsMgr() - CallRefId(1511) CalLRefIdForCC(1511) bSdpOffer(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.522
SIP Signalling(2492): ./src/sgSdpService.cpp:1840 GenerateLocalCapsMgr() - bAnatEnabled(0) bSDESenabled(0) bDualstack(0) bPayloadSecurityEnabled(0) eMediaIPMode(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.523
SIP Signalling(2492): ./src/sgSdpService.cpp:1849 GenerateLocalCapsMgr() - based on 1 RTPSession(s)

___TRACE:___   Thu Jan  9 17:10:17 2014.523
SIP Signalling(2492): ./src/sgSdpService.cpp:1890 GenerateLocalCapsMgr() - uNumSockets(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.523
SIP Signalling(2492): ./src/sgSdpService.cpp:1900 GenerateLocalCapsMgr() - IPV4 socket found

___TRACE:___   Thu Jan  9 17:10:17 2014.523
SIP Signalling(2492): ./src/sgSdpService.cpp:1936 GenerateLocalCapsMgr() - m-line(0) set to RtpMediaType(0) eRTPAVP

___TRACE:___   Thu Jan  9 17:10:17 2014.525
SIP Signalling(2492): ./src/sgSdpService.cpp:2083 GenerateLocalCapsMgr() - remove m-line(0) local connections data

___TRACE:___   Thu Jan  9 17:10:17 2014.525
SIP Signalling(2492): ./src/sgSdpService.cpp:2171 GenerateLocalCapsMgr() - GetCapsLocal((nil)) GetCapsRemote(0xaa13b8) GetCapsNegotiated((nil)) GetCapsNegotiatedLast((nil)) HasRemoteSdpOriginChanged(1) bSdpOffer(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.525
SIP Signalling(2492): ./src/sgSdpService.cpp:2266 GenerateLocalCapsMgr() - setting streams to bInactive(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.525
SIP Signalling(2492): ./src/sgSdpService.cpp:2296 GenerateLocalCapsMgr() - CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) bInactive(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.525
SIP Signalling(2492): ./src/sgSdpService.cpp:2442 GenerateSdpAnswer() - CallRefId(1511) CallRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:17 2014.529
SIP Signalling(2492): ./src/sgSdpService.cpp:2527 GenerateSdpAnswer() - res(0, S:OK)

___TRACE:___   Thu Jan  9 17:10:17 2014.529
SIP Signalling(2492): ./src/sgSdpService.cpp:2564 GenerateSdpAnswer() - no SRTP

___TRACE:___   Thu Jan  9 17:10:17 2014.529
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:2637 GenerateSdpAnswer() - supported audio stream at index(0), res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:2649 GenerateSdpAnswer() - supported media found at index(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:3604 GetRtpSession() - iPort(5006) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:3617 GetRtpSession() - pReturnSession[0](0xac2a90)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:3630 GetRtpSession() - pReturnSession[0](0xac2a90) - port(5006) iPort(5006) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.530
SIP Signalling(2492): ./src/sgSdpService.cpp:3684 SetSessionDirection() - CallRefId(1511) CallState(0,eCALL_NULL) RtpMediaType(0) eSessionDirection(5) bFromVerifyAnswer(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgSdpService.cpp:2776 GenerateSdpAnswer() - no video supported, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched(0xac2a90) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched((nil)) eRtpMediaType(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgSdpService.cpp:2931 GenerateSdpAnswer() - CallRefId(1511) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) CurrentCallAction(0,eCALL_A_NULL) bHoldIndication(0) bHoldUsingSendOnly(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:431 CallState - Call(1511) transition from (0,eCALL_NULL) to (1,eCALL_CONNECTED)

___TRACE:___   Thu Jan  9 17:10:17 2014.531
SIP Signalling(2492): ./src/sgSdpService.cpp:3031 GenerateSdpAnswer() success generating answer!

___TRACE:___   Thu Jan  9 17:10:17 2014.532
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (0,SDP_NULL) to (2,SDP_OFFER_RECEIVED)

___TRACE:___   Thu Jan  9 17:10:17 2014.532
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(1511) transition from (0,eCALL_P_NULL) to (1,eCALL_P_INITIAL)

___TRACE:___   Thu Jan  9 17:10:17 2014.532
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(2,SDP_OFFER_RECEIVED) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.533
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:678 SetRemoteUriDefault() - Call(1511) Display(Caller) User(016xxx)

___TRACE:___   Thu Jan  9 17:10:17 2014.533
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:838 RemoteCallSecurityIndication - Call(1511) transition from (-2147483643,FE:Null pointer) to (2,SI:False)

___TRACE:___   Thu Jan  9 17:10:17 2014.533
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:618 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(1511)

___TRACE:___   Thu Jan  9 17:10:17 2014.533
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:606 SIG->CC: sgEvtCallNewINVITE()/sgCallStateIncoming - CallRefId(1511) CallRefIdForCC(1511) ResourcePriority(99) ResourcePriorityToCC(0)

~~~DEBUG:~~~   Thu Jan  9 17:10:17 2014.533
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 9

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_new_incoming_call:   S_FM_idle ---> S_FM_new_call_incoming :  : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.534
SIP Call Control(2492): ./src/cc_tracing.c:650 CALLREC ALLOC = 1511 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.535
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.535
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_incoming:   S_CC_idle ---> S_CC_call_incoming : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.535
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.535
SIP Call Control(2492): ./src/cc_notify.c:62 # calls(incremented): 1

___TRACE:___   Thu Jan  9 17:10:17 2014.535
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

---INFO:---    Thu Jan  9 17:10:17 2014.568
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:17 2014.570
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:17 2014.570
ClockServiceInvoker(2265): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getDisplayTimeVal;3;req;

___TRACE:___   Thu Jan  9 17:10:17 2014.571
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 3

___TRACE:___   Thu Jan  9 17:10:17 2014.571
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_wait:   S_CC_call_incoming ---> S_CC_call_ringing_local : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.571
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_wait:   S_FM_new_call_incoming ---> S_FM_primary_call_wait_state :  : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 4 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.572
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:17 2014.615
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 7; state = 2

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgCDispatcher.cpp:617 CC->SIG:  sgCallAcceptIC:

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sg_call_service_int.cpp:545 sgAcceptIC() - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) suppressTalkEvent(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(2,SDP_OFFER_RECEIVED) eTypeOfMessage(2) bIncludeSdp(0) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgSdpService.cpp:908 SdpStateEventMachine() - INVITE_RESxxx_OUTGOING: NULL

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(1511) transition from (1,eCALL_P_INITIAL) to (2,eCALL_P_EARLY)

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(2,SDP_OFFER_RECEIVED) eCallEvent(0) bIncludeSdp(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.617
SIP Signalling(2492): ./src/sgSdpService.cpp:194 GetSdp() - CallRefId(1511) CallRefIdForCC(1511) bIncludeSdp(0)

___TRACE:___   Thu Jan  9 17:10:17 2014.618
SIP Signalling(2492): ./src/sg_call_service_int.cpp:616 sgAcceptIC()/RingA - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:17 2014.618
SIP Signalling(2492): ./src/sg_engine.cpp:2598 RingA()

___TRACE:___   Thu Jan  9 17:10:17 2014.621
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 16, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:17 2014.621
SIP Signalling(2695): ./src/sg_engine.cpp:2610 InternalRingA()

___TRACE:___   Thu Jan  9 17:10:17 2014.621
SIP Signalling(2695): ./src/sg_engine.cpp:2618 InternalRingA() - unCallRefId(1511), unLineId(1)

___TRACE:___   Thu Jan  9 17:10:17 2014.631
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bKB286FD255FD4497D
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
Call-ID: [email protected]
CSeq: 14 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "621" <sip:[email protected]:5060;transport=udp>
Server: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:22 2014.494
SIP Call Control(3332): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.494
SIP Call Control(3332): ./src/cc_tracing.c:473 Line 1: E_FM_call_answer:   S_FM_primary_call_wait_state ---> S_FM_primary_call_answer :  : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 4 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_tracing.c:473 Line 1: E_FML_FORCE: :   S_FM_primary_call_answer ---> S_FM_primary_call_wait_state :  : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_events.c:807 Exit action not defined for state : 4

___TRACE:___   Thu Jan  9 17:10:22 2014.495
SIP Call Control(3332): ./src/cc_tracing.c:473 Line 1: E_CC_call_answer:   S_CC_call_ringing_local ---> S_CC_call_connecting : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 1511, newState = ccCallConnecting, traceId = '')

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_notify.c:823 Call ref=1511: Old state=ccNull: New state=ccCallConnecting:

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.496
SIP Call Control(3332): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.507
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 8; state = 2

___TRACE:___   Thu Jan  9 17:10:22 2014.507
SIP Signalling(2492): ./src/sgCDispatcher.cpp:622 CC->SIG:  sgCallConnect:

___TRACE:___   Thu Jan  9 17:10:22 2014.507
SIP Signalling(2492): ./src/sg_call_service_int.cpp:652 sgConnect() - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL), InactiveFlag(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.507
SIP Signalling(2492): ./src/sgCDatabaseIf.cpp:800 GetVideoCapability() - eVideoCapability(1)

___TRACE:___   Thu Jan  9 17:10:22 2014.507
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(2,SDP_OFFER_RECEIVED) eTypeOfMessage(4) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgSdpService.cpp:901 SdpStateEventMachine() - INVITE_RESxxx_OUTGOING: NEGOTIATED

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:339 SetCapsToUseForSdp() - using eCAPS_MGR_NEGOTIATED for SDP

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (2,SDP_OFFER_RECEIVED) to (3,SDP_ANSWER_SENT)

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(1511) transition from (2,eCALL_P_EARLY) to (3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(3,SDP_ANSWER_SENT) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgSdpService.cpp:194 GetSdp() - CallRefId(1511) CallRefIdForCC(1511) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgSdpService.cpp:225 GetSdp() - generating SDP based on NEGOTIATED_CAPS

___TRACE:___   Thu Jan  9 17:10:22 2014.508
SIP Signalling(2492): ./src/sgSdpService.cpp:416 GetSdp() - szSessionAddr(192.168.3.162) strLocalAddr.CStr(192.168.3.162)

___TRACE:___   Thu Jan  9 17:10:22 2014.509
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:517 GetSdpId() - m_bRemoteSdpOriginChanged reset

___TRACE:___   Thu Jan  9 17:10:22 2014.509
SIP Signalling(2492): ./src/sgSdpService.cpp:458 GetSdp() - sdpPacket.IsValid(1) GetSessionTimeEnable(1) GetServerType(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.509
SIP Signalling(2492): ./src/sgSdpService.cpp:464 GetSdp() - Storing Sdp for session refresh for Call(1511)

___TRACE:___   Thu Jan  9 17:10:22 2014.510
SIP Signalling(2492): ./src/sg_call_service_int.cpp:760 sgConnect()/AcceptA - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) Code(200) Reason(OK)

___TRACE:___   Thu Jan  9 17:10:22 2014.511
SIP Signalling(2492): ./src/sg_engine.cpp:1787 AcceptA()

***ERROR:***   Thu Jan  9 17:10:22 2014.515
CSTA_Service(2516): ./src/CSTAServiceCommsEventsSIP.cpp:349 callStateChange() - implementation incomplete

___TRACE:___   Thu Jan  9 17:10:22 2014.522
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(1, 14, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:22 2014.523
SIP Signalling(2695): ./src/sg_engine.cpp:1799 InternalAcceptA()

___TRACE:___   Thu Jan  9 17:10:22 2014.535
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bKB286FD255FD4497D
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
Call-ID: [email protected]
CSeq: 14 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "621" <sip:[email protected]:5060;transport=udp>
Server: OpenStage_80_V3 R1.49.0      SIP  131105
Session-Expires: 3600;refresher=uas
Supported: replaces, timer, 100rel
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 230

v=0
o=OpenStage-Line_1 573811443 778472196 IN IP4 192.168.3.162
s=SIP Call
c=IN IP4 192.168.3.162
t=0 0
m=audio 5006 RTP/AVP 8 0 101
a=rtpmap:101 telephone-event/8000
a=silenceSupp:off - - - -
a=fmtp:101 0-15
a=sendrecv


___TRACE:___   Thu Jan  9 17:10:22 2014.536
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:59 GetRtpSessionToBeUsed() - returns pRtpSession(0xac2a90) SessionHandle(19) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.537
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:324 sgSetRemoteSessionDescription: pRtpSession(0xac2a90) SessionHandle(19) - <media-sessiondescription><remote-socket><ip-addr-v4>192.168.3.254</ip-addr-v4><port>7078</port><rtcp-port>0</rtcp-port></remote-socket><ptime>0</ptime><resource-priority>0</resource-priority><audio-silence-suppression>false</audio-silence-suppression><audio><audio-mode><codec>PCMA</codec><order>1</order></audio-mode></audio><dtmf-payload>101</dtmf-payload><qdc><subscriber-no>621</subscriber-no><subscriber-type>2</subscriber-type><call-id>[email protected]</call-id><hipath-switch-id>Unknown</hipath-switch-id></qdc></media-sessiondescription>

___TRACE:___   Thu Jan  9 17:10:22 2014.551
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
ACK sip:[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/udp 192.168.3.254:5060;branch=z9hG4bK69146BF8ABFDD1CB
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
Call-ID: [email protected]
CSeq: 14 ACK
Contact: <sip:[email protected]>
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:22 2014.551
SIP Signalling(2696): ./src/sg_engine.cpp:9186 EvPacketReceived(ISipTransportObserver) - REQ Method(ACK)

___TRACE:___   Thu Jan  9 17:10:22 2014.552
SIP Signalling(2695): ./src/sg_engine.cpp:4391 EvOnPacketReceived(ACK)

___TRACE:___   Thu Jan  9 17:10:22 2014.553
SIP Signalling(2695): ./src/sg_engine.cpp:4642 EvAcknowledged(ISipSessionSvc)

___TRACE:___   Thu Jan  9 17:10:22 2014.553
SIP Signalling(2695): ./src/sg_engine.cpp:4694 EvAcknowledged() - CallRefId(1511)

~~~DEBUG:~~~   Thu Jan  9 17:10:22 2014.553
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:22 2014.685
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:329 setRemoteSessionDescription - success:

___TRACE:___   Thu Jan  9 17:10:22 2014.685
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:500 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Jan  9 17:10:22 2014.685
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:398 sgSetSessionDirection() - eSessionDirection(0,eFromRtpSession)

___TRACE:___   Thu Jan  9 17:10:22 2014.686
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:410 sgSetSessionDirection() - pRtpSession(0xac2a90): SessionHandle(19) SessionDirection(0,eSENDRECV)

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:443 setDirection - success

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5831 SetLocalStream() - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) SdpState(3,SDP_ANSWER_SENT) CallPhase(3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Signalling(2492): ./src/sg_call_service_int.cpp:818 sgConnect()/sgCallStateConnected - CallRefId(1511) CallRefIdForCC(1511) ResourcePriority(99) ResourcePriorityToCC(0)

~~~DEBUG:~~~   Thu Jan  9 17:10:22 2014.734
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 16

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 1511

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 6

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_connected:   S_CC_call_connecting ---> S_CC_call_connected : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.734
SIP Call Control(2492): ./src/cc_events.c:1506 ACT_call_connected : 1511

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_call_connected:   S_FM_primary_call_wait_state ---> S_FM_primary_call_connected :  : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 7 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccHeldPartyFollowOn : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccIsolatedCall : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.735
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 1511, newState = ccCallConnected, traceId = '')

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=1511: Old state=ccCallConnecting: New state=ccCallConnected:

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_notify.c:827 Not joinable = 2

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_notify.c:976 **** ccCallStateChange - reached ccCallConnected **** (thisCall = 1511, newState =ccCallConnected)

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

***ERROR:***   Thu Jan  9 17:10:22 2014.736
SIP Communications Service(2492): ./src/sfi_utils.c:766 sfiConvert_TsgPreemptionCause - unrecognised type!

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.736
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:22 2014.756
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 116; state = 2

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgCDispatcher.cpp:161 Engine->CallService: sipCallACK

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1228 sgEvtCallACK() - START: CallRefId(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(3,SDP_ANSWER_SENT) eTypeOfMessage(7) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgCSipPacket.cpp:882 GetFirstApplicationSdpPayload() - pSipMessageBody is NULL

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgSdpService.cpp:2389 GenerateRemoteCapsMgr() - CallRefId(1511) CallRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgSdpService.cpp:2394 GenerateRemoteCapsMgr() - pPayload(NULL)

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (3,SDP_ANSWER_SENT) to (5,SDP_NEGOTIATION_FINISHED)

___TRACE:___   Thu Jan  9 17:10:22 2014.757
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(5,SDP_NEGOTIATION_FINISHED) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:22 2014.758
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:670 SetRemoteUriDefault() - exit Call(1511) Display(Caller) User(016xxx)

___TRACE:___   Thu Jan  9 17:10:22 2014.758
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1286 sgEvtCallACK() - MID: CallRefId(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:22 2014.758
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:59 GetRtpSessionToBeUsed() - returns pRtpSession(0xac2a90) SessionHandle(19) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:22 2014.758
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5831 SetLocalStream() - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) SdpState(5,SDP_NEGOTIATION_FINISHED) CallPhase(3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Jan  9 17:10:22 2014.758
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1386 sgEvtCallACK() - END: CallRefId(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

---INFO:---    Thu Jan  9 17:10:22 2014.791
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:22 2014.793
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:22 2014.796
ClockServiceInvoker(2265): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getDisplayTimeVal;3;req;

***ERROR:***   Thu Jan  9 17:10:25 2014.788
PhysicalInterfaceService(2262): ./src/PhysSubscriptionHelper.cpp:1042 distributeButtonEventToSubscribers - observer subscribed for function & key press (OVERLAP)

***ERROR:***   Thu Jan  9 17:10:25 2014.788
PhysicalInterfaceService(2262): ./src/PhysSubscriptionHelper.cpp:1051 Removed PhysicalEventObserver for CallViewPhonelet/Channel

***ERROR:***   Thu Jan  9 17:10:25 2014.948
PhysicalInterfaceService(2262): ./src/PhysSubscriptionHelper.cpp:1042 distributeButtonEventToSubscribers - observer subscribed for function & key press (OVERLAP)

***ERROR:***   Thu Jan  9 17:10:25 2014.948
PhysicalInterfaceService(2262): ./src/PhysSubscriptionHelper.cpp:1051 Removed PhysicalEventObserver for CallViewPhonelet/Channel

___TRACE:___   Thu Jan  9 17:10:25 2014.968
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.968
SIP Call Control(2690): ./src/cc_tracing.c:473 Line 1: E_FM_call_hold:   S_FM_primary_call_connected ---> S_FM_primary_call_hold_req :  : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.969
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 7 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.969
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.969
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_events.c:807 Exit action not defined for state : 7

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_tracing.c:473 Line 1: E_CC_call_hold_request_farend:   S_CC_call_connected ---> S_CC_call_hold_request_farend : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 1511, newState = ccCallHolding, traceId = '')

___TRACE:___   Thu Jan  9 17:10:25 2014.970
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_notify.c:823 Call ref=1511: Old state=ccCallConnected: New state=ccCallHolding:

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.971
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 20; state = 2

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgCDispatcher.cpp:642 CC->SIG:  sgCallHold:

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sg_call_service_int.cpp:939 sgCallHold() - from CC for callRefId(1511)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sg_call_service_int.cpp:1011 sgCallHoldInternal() - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:484 CurrentCallAction - Call(1511) transition from (0,eCALL_A_NULL) to (1,eCALL_A_LOCAL_HOLD)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgCDatabaseIf.cpp:800 GetVideoCapability() - eVideoCapability(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:756 KW: eRtpMediaType(0) bHasRtp(1) bHasSrtpMikey(0) bHasSrtpSdes(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:831 GenerateRtpSessionsHelper() - created a vector(0xada090) with 0 RtpSession(s) eRtpMediaType(0) total(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:720 GenerateRtpSessions() - created a vector(0xada090) with 1 RtpSession(s)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(5,SDP_NEGOTIATION_FINISHED) eTypeOfMessage(0) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.978
SIP Signalling(2492): ./src/sgSdpService.cpp:1814 GenerateLocalCapsMgr() - CallRefId(1511) CalLRefIdForCC(1511) bSdpOffer(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.979
SIP Signalling(2492): ./src/sgSdpService.cpp:1840 GenerateLocalCapsMgr() - bAnatEnabled(0) bSDESenabled(0) bDualstack(0) bPayloadSecurityEnabled(0) eMediaIPMode(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.979
SIP Signalling(2492): ./src/sgSdpService.cpp:1849 GenerateLocalCapsMgr() - based on 1 RTPSession(s)

___TRACE:___   Thu Jan  9 17:10:25 2014.979
SIP Signalling(2492): ./src/sgSdpService.cpp:1890 GenerateLocalCapsMgr() - uNumSockets(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.979
SIP Signalling(2492): ./src/sgSdpService.cpp:1900 GenerateLocalCapsMgr() - IPV4 socket found

___TRACE:___   Thu Jan  9 17:10:25 2014.980
SIP Signalling(2492): ./src/sgSdpService.cpp:1936 GenerateLocalCapsMgr() - m-line(0) set to RtpMediaType(0) eRTPAVP

___TRACE:___   Thu Jan  9 17:10:25 2014.981
SIP Signalling(2492): ./src/sgSdpService.cpp:2083 GenerateLocalCapsMgr() - remove m-line(0) local connections data

___TRACE:___   Thu Jan  9 17:10:25 2014.982
SIP Signalling(2492): ./src/sgSdpService.cpp:2171 GenerateLocalCapsMgr() - GetCapsLocal(0xadd388) GetCapsRemote(0xaa13b8) GetCapsNegotiated(0xac0f78) GetCapsNegotiatedLast(0xab51d0) HasRemoteSdpOriginChanged(0) bSdpOffer(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.982
SIP Signalling(2492): ./src/sgSdpService.cpp:2182 GenerateLocalCapsMgr() - LocalCaps are based on LastNegotiatedCaps

___TRACE:___   Thu Jan  9 17:10:25 2014.996
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:535 MergeCapsMgr() - pFirstCapsMgr->GetNbStreams(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.996
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:705 MergeCapsMgr() - unIndexFirst(0) eTransportProtocol(0) eAddressType(0) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.996
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:712 MergeCapsMgr() - Stream eMediaType(0) eTransportProtocol(0) eAddressType(0) added at unIndexThis(0), based on unIndexSecond(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.996
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:786 MergeCapsMgr() - unNbOfStreamsSecond(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.996
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:789 MergeCapsMgr() - unIndexSecond(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.997
SIP Signalling(2492): ./src/sgSdpService.cpp:2266 GenerateLocalCapsMgr() - setting streams to bInactive(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.997
SIP Signalling(2492): ./src/sgSdpService.cpp:2296 GenerateLocalCapsMgr() - CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) bInactive(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.997
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:456 CallSubState - Call(1511) transition from (0,eCALL_SS_NULL) to (1,eCALL_SS_LOCAL_HOLD_PENDING)

___TRACE:___   Thu Jan  9 17:10:25 2014.997
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:484 CurrentCallAction - Call(1511) transition from (1,eCALL_A_LOCAL_HOLD) to (0,eCALL_A_NULL)

___TRACE:___   Thu Jan  9 17:10:25 2014.997
SIP Signalling(2492): ./src/sgSdpService.cpp:1224 SdpStateEventMachine() - INVITE_REQ_OUTGOING: LOCAL

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:339 SetCapsToUseForSdp() - using eCAPS_MGR_LOCAL for SDP

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (5,SDP_NEGOTIATION_FINISHED) to (1,SDP_OFFER_SENT)

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(1,SDP_OFFER_SENT) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgSdpService.cpp:194 GetSdp() - CallRefId(1511) CallRefIdForCC(1511) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgSdpService.cpp:221 GetSdp() - generating SDP based on LOCAL_CAPS

___TRACE:___   Thu Jan  9 17:10:25 2014.998
SIP Signalling(2492): ./src/sgSdpService.cpp:416 GetSdp() - szSessionAddr(192.168.3.162) strLocalAddr.CStr(192.168.3.162)

___TRACE:___   Thu Jan  9 17:10:25 2014.999
SIP Signalling(2492): ./src/sgSdpService.cpp:458 GetSdp() - sdpPacket.IsValid(1) GetSessionTimeEnable(1) GetServerType(0)

___TRACE:___   Thu Jan  9 17:10:25 2014.999
SIP Signalling(2492): ./src/sgSdpService.cpp:464 GetSdp() - Storing Sdp for session refresh for Call(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.001
SIP Signalling(2492): ./src/sg_call_service_int.cpp:1098 sgCallHoldMsg() - CallRefId(1511) OriginalCallRefId(0) TransfertToCallRefId(0) CallRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.002
SIP Signalling(2492): ./src/sg_call_service_int.cpp:1119 sgCallHoldMsg()/ReInviteA - CallRefId(1511) CallRefIdForCC(1511) CallState(1,eCALL_CONNECTED) CallSubState(1,eCALL_SS_LOCAL_HOLD_PENDING)

___TRACE:___   Thu Jan  9 17:10:26 2014.002
SIP Signalling(2492): ./src/sg_engine.cpp:3007 ReInviteA()

___TRACE:___   Thu Jan  9 17:10:26 2014.048
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 18, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:26 2014.049
SIP Signalling(2695): ./src/sg_engine.cpp:3019 InternalReInviteA()

___TRACE:___   Thu Jan  9 17:10:26 2014.049
SIP Signalling(2695): ./src/sg_engine.cpp:3027 InternalReInviteA() - unCallRefId(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.059
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bKabf8cd755e92f08dc
Max-Forwards: 70
From: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
To: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
Call-ID: [email protected]
CSeq: 1365532475 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "621" <sip:[email protected]:5060;transport=udp>
Min-SE: 90
Session-Expires: 3600;refresher=uac
Supported: replaces, 100rel, timer
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 321

v=0
o=OpenStage-Line_1 573811443 778472197 IN IP4 192.168.3.162
s=SIP Call
c=IN IP4 192.168.3.162
t=0 0
m=audio 5006 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=silenceSupp:off - - - -
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=inactive


~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.060
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:26 2014.061
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 144; state = 2

___TRACE:___   Thu Jan  9 17:10:26 2014.061
SIP Signalling(2492): ./src/sgCDispatcher.cpp:338 Engine->CallService: sipCallProceeding

___TRACE:___   Thu Jan  9 17:10:26 2014.062
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5114 SIG->CC: sgEvtCallProceeding()/sgCallStateProceeding - CallRefId(1511)

~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.062
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:26 2014.062
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 12

___TRACE:___   Thu Jan  9 17:10:26 2014.062
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 1511

***ERROR:***   Thu Jan  9 17:10:26 2014.062
SIP Call Control(2492): ./src/cc_tracing.c:470 Line 1: E_CC_call_proceeding:   S_CC_call_hold_request_farend ---> OUT OF CONTEXT EVENT : 1511

___TRACE:___   Thu Jan  9 17:10:26 2014.062
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_proceeding:   S_CC_call_hold_request_farend ---> OUT OF CONTEXT EVENT : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.086
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bKabf8cd755e92f08dc
From: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
To: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
Call-ID: [email protected]
CSeq: 1365532475 INVITE
Contact: <sip:[email protected]>
Session-Expires: 3600;refresher=uac
Min-SE: 90
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 246

v=0
o=user 12347479 12347480 IN IP4 192.168.3.254
s=SIP Call
c=IN IP4 192.168.3.254
t=0 0
m=audio 7078 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=inactive
a=rtcp:7079


___TRACE:___   Thu Jan  9 17:10:26 2014.086
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:26 2014.088
SIP Signalling(2695): ./src/sg_engine.cpp:6582 EvSuccess(ISipSessionSvc)

___TRACE:___   Thu Jan  9 17:10:26 2014.089
SIP Signalling(2695): ./src/sg_engine.cpp:6666 EvSuccess() - first 200OK received for this Call(1511) DialogSize(1)

~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.089
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:26 2014.090
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 123; state = 2

___TRACE:___   Thu Jan  9 17:10:26 2014.090
SIP Signalling(2492): ./src/sgCDispatcher.cpp:203 Engine->CallService: sipCallSuccess

___TRACE:___   Thu Jan  9 17:10:26 2014.090
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2421 sgEvtCallSuccess() - START: CallRefId(1511) CallState(1,eCALL_CONNECTED) CallSubState(1,eCALL_SS_LOCAL_HOLD_PENDING)

___TRACE:___   Thu Jan  9 17:10:26 2014.090
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(1,SDP_OFFER_SENT) eTypeOfMessage(5) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.091
SIP Signalling(2492): ./src/sgCSipPacket.cpp:999 HasContentTypeHeader() - Content(application/sdp) available

___TRACE:___   Thu Jan  9 17:10:26 2014.091
SIP Signalling(2492): ./src/sgCSipPacket.cpp:918 GetFirstPayload() - application/sdp found

___TRACE:___   Thu Jan  9 17:10:26 2014.091
SIP Signalling(2492): ./src/sgSdpService.cpp:2389 GenerateRemoteCapsMgr() - CallRefId(1511) CallRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.094
SIP Signalling(2492): ./src/sgSdpService.cpp:3044 VerifySdpAnswer() - CallRefId(1511) CalLRefIdForCC(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.095
SIP Signalling(2492): ./src/sgSdpService.cpp:3071 VerifySdpAnswer() - OKAY, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:26 2014.095
SIP Signalling(2492): ./src/sgSdpService.cpp:3091 VerifySdpAnswer() - Media Supported res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:26 2014.095
SIP Signalling(2492): ./src/sgSdpService.cpp:3132 VerifySdpAnswer() - really OKAY, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:26 2014.095
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.095
SIP Signalling(2492): ./src/sgSdpService.cpp:3170 VerifySdpAnswer() - CallRefId(1511) CallState(1,eCALL_CONNECTED) CallSubState(1,eCALL_SS_LOCAL_HOLD_PENDING) CurrentCallAction(0,eCALL_A_NULL) bHoldIndication(1) bHoldUsingSendOnly(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:431 CallState - Call(1511) transition from (1,eCALL_CONNECTED) to (2,eCALL_LOCAL_HOLD)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:456 CallSubState - Call(1511) transition from (1,eCALL_SS_LOCAL_HOLD_PENDING) to (0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:1322 StopRetryTimer() - stopping RetryTimer((nil)) at call(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:1235 ProcessNextActionOnQueue() - CallRefId(1511) no action on queue

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgSdpService.cpp:3604 GetRtpSession() - iPort(5006) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgSdpService.cpp:3617 GetRtpSession() - pReturnSession[0](0xac2a90)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgSdpService.cpp:3630 GetRtpSession() - pReturnSession[0](0xac2a90) - port(5006) iPort(5006) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.096
SIP Signalling(2492): ./src/sgSdpService.cpp:3684 SetSessionDirection() - CallRefId(1511) CallState(2,eCALL_LOCAL_HOLD) RtpMediaType(0) eSessionDirection(6) bFromVerifyAnswer(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.097
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Jan  9 17:10:26 2014.097
SIP Signalling(2492): ./src/sgSdpService.cpp:3448 VerifySdpAnswer() - no video supported, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:26 2014.097
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched(0xac2a90) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.097
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched((nil)) eRtpMediaType(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.098
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (1,SDP_OFFER_SENT) to (4,SDP_ANSWER_RECEIVED)

___TRACE:___   Thu Jan  9 17:10:26 2014.098
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(4,SDP_ANSWER_RECEIVED) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:670 SetRemoteUriDefault() - exit Call(1511) Display(Caller) User(016xxx)

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:838 RemoteCallSecurityIndication - Call(1511) transition from (2,SI:False) to (2,SI:False)

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sgCSipPacket.cpp:747 GetRequestUri() - SipPacket is not a request

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:618 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(1511)

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:798 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2598 SIG->CC: sgEvtCallSuccess()/sgCallHoldingIndication - CallRefId(1511) CallRefIdForCC(1511) holdingType(2)

~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.099
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 21

___TRACE:___   Thu Jan  9 17:10:26 2014.099
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 1511

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 14

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_held:   S_CC_call_hold_request_farend ---> S_CC_call_held : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 1511, newState = ccCallOnHold, traceId = '')

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.100
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=1511: Old state=ccCallHolding: New state=ccCallOnHold:

___TRACE:___   Thu Jan  9 17:10:26 2014.101
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.101
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.101
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.133
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.133
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_call_held:   S_FM_primary_call_hold_req ---> S_FM_primary_call_connected :  : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.134
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 15 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.134
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.135
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.135
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.136
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.136
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.138
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(1511) CalLRefIdForCC(1511) SdpState(4,SDP_ANSWER_RECEIVED) eTypeOfMessage(6) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.138
SIP Signalling(2492): ./src/sgSdpService.cpp:1148 SdpStateEventMachine() - INVITE_ACK_OUTGOING: NULL

___TRACE:___   Thu Jan  9 17:10:26 2014.139
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:339 SetCapsToUseForSdp() - using eCAPS_MGR_NULL for SDP

___TRACE:___   Thu Jan  9 17:10:26 2014.139
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(1511) transition from (4,SDP_ANSWER_RECEIVED) to (5,SDP_NEGOTIATION_FINISHED)

___TRACE:___   Thu Jan  9 17:10:26 2014.140
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(1511) rCallRecord.GetSdpStateStr(5,SDP_NEGOTIATION_FINISHED) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.141
SIP Signalling(2492): ./src/sgSdpService.cpp:194 GetSdp() - CallRefId(1511) CallRefIdForCC(1511) bIncludeSdp(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.141
SIP Signalling(2492): ./src/sg_engine.cpp:2866 AcknowledgeA()

___TRACE:___   Thu Jan  9 17:10:26 2014.142
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:59 GetRtpSessionToBeUsed() - returns pRtpSession(0xac2a90) SessionHandle(19) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.149
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:324 sgSetRemoteSessionDescription: pRtpSession(0xac2a90) SessionHandle(19) - <media-sessiondescription><remote-socket><ip-addr-v4>192.168.3.254</ip-addr-v4><port>7078</port><rtcp-port>0</rtcp-port></remote-socket><ptime>0</ptime><resource-priority>0</resource-priority><audio-silence-suppression>false</audio-silence-suppression><audio><audio-mode><codec>PCMA</codec><order>1</order></audio-mode></audio><dtmf-payload>101</dtmf-payload><qdc><subscriber-no>621</subscriber-no><subscriber-type>2</subscriber-type><call-id>[email protected]</call-id><hipath-switch-id>Unknown</hipath-switch-id></qdc></media-sessiondescription>

___TRACE:___   Thu Jan  9 17:10:26 2014.164
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:329 setRemoteSessionDescription - success:

___TRACE:___   Thu Jan  9 17:10:26 2014.164
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:500 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Jan  9 17:10:26 2014.165
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:398 sgSetSessionDirection() - eSessionDirection(1,eINACTIVE)

___TRACE:___   Thu Jan  9 17:10:26 2014.165
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:410 sgSetSessionDirection() - pRtpSession(0xac2a90): SessionHandle(19) SessionDirection(1,eINACTIVE)

___TRACE:___   Thu Jan  9 17:10:26 2014.181
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:443 setDirection - success

___TRACE:___   Thu Jan  9 17:10:26 2014.181
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5831 SetLocalStream() - CallRefId(1511) CallRefIdForCC(1511) CallState(2,eCALL_LOCAL_HOLD) CallSubState(0,eCALL_SS_NULL) SdpState(5,SDP_NEGOTIATION_FINISHED) CallPhase(3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Jan  9 17:10:26 2014.182
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2761 sgEvtCallSuccess() - END: CallRefId(1511) CallState(2,eCALL_LOCAL_HOLD) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:26 2014.531
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 17, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:26 2014.532
SIP Signalling(2695): ./src/sg_engine.cpp:2878 InternalAcknowledgeA()

___TRACE:___   Thu Jan  9 17:10:26 2014.532
SIP Signalling(2695): ./src/sg_engine.cpp:2887 InternalAcknowledgeA() - unCallRefId(1511), unLineId(1) IsProvisionalAcknowledge(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.537
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bKb3432ff9f01b5c2c2
Max-Forwards: 70
From: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
To: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
Call-ID: [email protected]
CSeq: 1365532475 ACK
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccPreConsultationCall : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_tracing.c:650 CALLREC ALLOC = 123 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_tracing.c:685 Set calltype = ccPreConsultationCall for callref 123 : 0

***ERROR:***   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_services.c:331 ccCallMake - check number of calls on line : 2

___TRACE:___   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.570
SIP Call Control(2690): ./src/cc_tracing.c:473 Line 1: E_FM_consultation_call:   S_FM_primary_call_connected ---> S_FM_consultation_call_req :  : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 15 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 2 current_state 0 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_tracing.c:473 Line 1: E_FM_wait:   S_FM_consultation_call_req ---> S_FM_consultation_proceeding :  : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 0 current_state 15 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.571
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 2 current_state 0 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.572
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.572
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.572
SIP Call Control(2690): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.572
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.572
SIP Call Control(2690): ./src/cc_tracing.c:685 Set calltype = ccHeldCall for callref 1511 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_events.c:807 Exit action not defined for state : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_tracing.c:473 Line 1: E_CC_call_make:   S_CC_idle ---> S_CC_call_setup : 123 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_events.c:1058 ACT_call_setup - existing calls : 2

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_events.c:1059 ACT_call_setup - new call ref : 123

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccPrimaryCall : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccSecondaryCall : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_events.c:1111 ACT_call_setup - found existing call ref : 1511

___TRACE:___   Thu Jan  9 17:10:26 2014.573
SIP Call Control(2690): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 123, newState = ccCallSettingUp, traceId = '')

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_notify.c:62 # calls(incremented): 2

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_notify.c:823 Call ref=123: Old state=ccNull: New state=ccCallSettingUp:

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.574
SIP Call Control(2690): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.587
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 5; state = 2

___TRACE:___   Thu Jan  9 17:10:26 2014.588
SIP Signalling(2492): ./src/sgCDispatcher.cpp:608 CC->SIG:  sgCallMake:

___TRACE:___   Thu Jan  9 17:10:26 2014.588
SIP Signalling(2492): ./src/sg_init_service.cpp:109 SetStackTime

---INFO:---    Thu Jan  9 17:10:26 2014.588
SIP Signalling(2492): ./src/sg_init_service.cpp:121 GMT: 2014 Jan 09 16:10:26 Thu

---INFO:---    Thu Jan  9 17:10:26 2014.588
SIP Signalling(2492): ./src/sg_init_service.cpp:129 UTC: y(114) m(0) d(9) h(16) m(10) s(26)

___TRACE:___   Thu Jan  9 17:10:26 2014.589
SIP Signalling(2492): ./src/sg_call_service_int.cpp:135 sgCallSetup() BRIDGE DATA: CallId() CallToUri() CallTo() CallFrom() Enabled(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.589
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:76 CreateCallRecord() - new CallRecord(123,0) created, 2 CallRecord(s) in total

___TRACE:___   Thu Jan  9 17:10:26 2014.926
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(1, 5, 0xa5a100)

___TRACE:___   Thu Jan  9 17:10:26 2014.926
SIP Signalling(2492): ./src/sg_sip_url_service.cpp:53 sgSetSipURL() - pInRemURL(**610) unLineId(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.927
SIP Signalling(2492): ./src/sg_sip_url_service.cpp:217 sgSetSipURL() - URI<sip:(**610)@(fritz.box):(5060)>

___TRACE:___   Thu Jan  9 17:10:26 2014.927
SIP Signalling(2492): ./src/sgCDatabaseIf.cpp:800 GetVideoCapability() - eVideoCapability(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.927
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:756 KW: eRtpMediaType(0) bHasRtp(0) bHasSrtpMikey(0) bHasSrtpSdes(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.934
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:168 sgCreateSession() - getLocalSessionDescription: <media-sessiondescription><ip-addr>192.168.3.162</ip-addr><port>5004</port><local-socket><ip-addr-v4>192.168.3.162</ip-addr-v4><port>5004</port><rtcp-port>5005</rtcp-port></local-socket><dtmf-payload>101</dtmf-payload><ptime>0</ptime><max-ptime>60</max-ptime><audio><codec>PCMA</codec><codec>PCMU</codec><codec>G729</codec></audio><ssrc>3021877012</ssrc></media-sessiondescription>

___TRACE:___   Thu Jan  9 17:10:26 2014.935
SIP Signalling(2492): ./src/sgCRtpSession.cpp:669 AddSocket RtpSession(0xabc2b0) ip(192.168.3.162) port(5004) rtcpport(5005) eIPV4

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:186 sgCreateSession() - pRtpSession(abc2b0) SessionHandle(20) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:782 GenerateRtpSessionsHelper() - New RtpSession created, pRtpSession(0xabc2b0)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:831 GenerateRtpSessionsHelper() - created a vector(0xac9240) with 1 RtpSession(s) eRtpMediaType(0) total(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:720 GenerateRtpSessions() - created a vector(0xac9240) with 1 RtpSession(s)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:224 SetRtpSessions() - replacing m_pvRtpSessions((nil)) with pRtpSession(0xac9240)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(123) CalLRefIdForCC(123) SdpState(0,SDP_NULL) eTypeOfMessage(0) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.937
SIP Signalling(2492): ./src/sgSdpService.cpp:1814 GenerateLocalCapsMgr() - CallRefId(123) CalLRefIdForCC(123) bSdpOffer(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.938
SIP Signalling(2492): ./src/sgSdpService.cpp:1840 GenerateLocalCapsMgr() - bAnatEnabled(0) bSDESenabled(0) bDualstack(0) bPayloadSecurityEnabled(0) eMediaIPMode(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.938
SIP Signalling(2492): ./src/sgSdpService.cpp:1849 GenerateLocalCapsMgr() - based on 1 RTPSession(s)

___TRACE:___   Thu Jan  9 17:10:26 2014.938
SIP Signalling(2492): ./src/sgSdpService.cpp:1890 GenerateLocalCapsMgr() - uNumSockets(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.938
SIP Signalling(2492): ./src/sgSdpService.cpp:1900 GenerateLocalCapsMgr() - IPV4 socket found

___TRACE:___   Thu Jan  9 17:10:26 2014.938
SIP Signalling(2492): ./src/sgSdpService.cpp:1936 GenerateLocalCapsMgr() - m-line(0) set to RtpMediaType(0) eRTPAVP

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:2083 GenerateLocalCapsMgr() - remove m-line(0) local connections data

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:2171 GenerateLocalCapsMgr() - GetCapsLocal((nil)) GetCapsRemote((nil)) GetCapsNegotiated((nil)) GetCapsNegotiatedLast((nil)) HasRemoteSdpOriginChanged(0) bSdpOffer(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:2266 GenerateLocalCapsMgr() - setting streams to bInactive(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:2296 GenerateLocalCapsMgr() - CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) bInactive(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:646 SdpStateEventMachine() - INVITE_REQ_OUTGOING: LOCAL

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:339 SetCapsToUseForSdp() - using eCAPS_MGR_LOCAL for SDP

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(123) transition from (0,SDP_NULL) to (1,SDP_OFFER_SENT)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(123) transition from (0,eCALL_P_NULL) to (1,eCALL_P_INITIAL)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(123) rCallRecord.GetSdpStateStr(1,SDP_OFFER_SENT) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.940
SIP Signalling(2492): ./src/sgSdpService.cpp:194 GetSdp() - CallRefId(123) CallRefIdForCC(123) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:26 2014.941
SIP Signalling(2492): ./src/sgSdpService.cpp:221 GetSdp() - generating SDP based on LOCAL_CAPS

___TRACE:___   Thu Jan  9 17:10:26 2014.941
SIP Signalling(2492): ./src/sgSdpService.cpp:416 GetSdp() - szSessionAddr(192.168.3.162) strLocalAddr.CStr(192.168.3.162)

___TRACE:___   Thu Jan  9 17:10:26 2014.942
SIP Signalling(2492): ./src/sgSdpService.cpp:458 GetSdp() - sdpPacket.IsValid(1) GetSessionTimeEnable(1) GetServerType(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.942
SIP Signalling(2492): ./src/sgSdpService.cpp:464 GetSdp() - Storing Sdp for session refresh for Call(123)

___TRACE:___   Thu Jan  9 17:10:26 2014.957
SIP Signalling(2492): ./src/sg_call_service_int.cpp:377 sgCallSetup()/InviteA - CallRefId(123) CallRefIdForCC(123)

___TRACE:___   Thu Jan  9 17:10:26 2014.957
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 13, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:26 2014.958
SIP Signalling(2695): ./src/sgCallInfoMgmt.cpp:58 CreateCallInfo() - new CallInfo(123) created, 2 CallInfo(s) in total

___TRACE:___   Thu Jan  9 17:10:26 2014.958
SIP Signalling(2695): ./src/sg_engine.cpp:1658 InternalInviteA() - rRemoteUri: user(**610) host(fritz.box) port(5060)

___TRACE:___   Thu Jan  9 17:10:26 2014.959
SIP Signalling(2695): ./src/sg_engine.cpp:11815 CreateSipContext() - CallInfo created, lineId(1), callRefId(123)

___TRACE:___   Thu Jan  9 17:10:26 2014.961
SIP Signalling(2695): ./src/sg_engine.cpp:10263 AddUserAgentSvc() - IPv4(192.168.3.162)(1) IPv6(NULL)(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.961
SIP Signalling(2695): ./src/sg_engine.cpp:11870 CreateSipContext() - LineId(1) TLS(0)

___TRACE:___   Thu Jan  9 17:10:26 2014.962
SIP Signalling(2695): ./src/sg_engine_auth.cpp:40 AddDigestClientAuthSvc()  SetLoopThreshold(3)

___TRACE:___   Thu Jan  9 17:10:26 2014.963
SIP Signalling(2695): ./src/sg_engine_rel.cpp:26 AddReliableProvisionalResponseSvc()

~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.969
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 144; state = 2

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Signalling(2492): ./src/sgCDispatcher.cpp:338 Engine->CallService: sipCallProceeding

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5114 SIG->CC: sgEvtCallProceeding()/sgCallStateProceeding - CallRefId(123)

~~~DEBUG:~~~   Thu Jan  9 17:10:26 2014.972
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 12

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 123

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 1

___TRACE:___   Thu Jan  9 17:10:26 2014.972
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_proceeding:   S_CC_call_setup ---> S_CC_call_proceeding : 123 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 123, newState = ccCallProceeding, traceId = '')

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=123: Old state=ccCallSettingUp: New state=ccCallProceeding:

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.973
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:26 2014.990
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
INVITE sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK0222e3e383e86b0bd
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>
Call-ID: 1598bafb2bd8099e
CSeq: 1953235264 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "621" <sip:[email protected]:5060;transport=udp>
Min-SE: 90
Session-Expires: 3600;refresher=uac
Supported: replaces, 100rel, timer
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 323

v=0
o=OpenStage-Line_1 1351807687 1867917740 IN IP4 192.168.3.162
s=SIP Call
c=IN IP4 192.168.3.162
t=0 0
m=audio 5004 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=silenceSupp:off - - - -
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv


___TRACE:___   Thu Jan  9 17:10:26 2014.997
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK0222e3e383e86b0bd
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>;tag=41C4D864DA59A866
Call-ID: 1598bafb2bd8099e
CSeq: 1953235264 INVITE
WWW-Authenticate: Digest realm="fritz.box", nonce="E96BCEC0AF705550"
User-Agent: FRITZ!OS
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:26 2014.998
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(401) Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:27 2014.001
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
ACK sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK0222e3e383e86b0bd
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>;tag=41C4D864DA59A866
Call-ID: 1598bafb2bd8099e
CSeq: 1953235264 ACK
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:27 2014.021
SIP Signalling(2695): ./src/sg_engine_auth.cpp:72 EvCredentialsRequired()

___TRACE:___   Thu Jan  9 17:10:27 2014.022
SIP Signalling(2695): ./src/sg_engine_auth.cpp:127 EvCredentialsRequired() - LineId(1) CallRefId(123)

---INFO:---    Thu Jan  9 17:10:27 2014.046
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:27 2014.047
ClockServiceInvoker(3330): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getDisplayTimeVal;3;req;

___TRACE:___   Thu Jan  9 17:10:27 2014.051
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:27 2014.054
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:27 2014.071
SIP Signalling(2695): ./src/sg_engine_auth.cpp:240 EvCredentialsRequired() - request reissued

___TRACE:___   Thu Jan  9 17:10:27 2014.079
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
INVITE sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK889b3186edbaa95f9
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>
Call-ID: 1598bafb2bd8099e
CSeq: 1953235265 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Authorization: Digest username="621",realm="fritz.box",nonce="E96BCEC0AF705550",uri="sip:**[email protected]:5060;transport=udp",response="19a9c6857b014d165194feeacc392c3a"
Contact: "621" <sip:[email protected]:5060;transport=udp>
Min-SE: 90
Session-Expires: 3600;refresher=uac
Supported: replaces, 100rel, timer
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 323

v=0
o=OpenStage-Line_1 1351807687 1867917740 IN IP4 192.168.3.162
s=SIP Call
c=IN IP4 192.168.3.162
t=0 0
m=audio 5004 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=silenceSupp:off - - - -
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv


___TRACE:___   Thu Jan  9 17:10:27 2014.095
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK889b3186edbaa95f9
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>
Call-ID: 1598bafb2bd8099e
CSeq: 1953235265 INVITE
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:27 2014.095
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(100) Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:27 2014.097
SIP Signalling(2695): ./src/sg_engine.cpp:5601 EvProgress(ISipSessionSvc)

~~~DEBUG:~~~   Thu Jan  9 17:10:27 2014.097
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:27 2014.098
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 122; state = 2

___TRACE:___   Thu Jan  9 17:10:27 2014.098
SIP Signalling(2492): ./src/sgCDispatcher.cpp:197 Engine->CallService: sipCallProgress

___TRACE:___   Thu Jan  9 17:10:27 2014.098
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1918 sgEvtCallProgress() - START: CallRefId(123) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:27 2014.099
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1975 sgEvtCallProgress() - END 100Trying (100)

___TRACE:___   Thu Jan  9 17:10:27 2014.114
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK889b3186edbaa95f9
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>;tag=7CE9230B7CB01DD1
Call-ID: 1598bafb2bd8099e
CSeq: 1953235265 INVITE
Contact: <sip:[email protected]>
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Content-Type: application/sdp
Content-Length: 246

v=0
o=user 12751746 12751746 IN IP4 192.168.3.254
s=SIP Call
c=IN IP4 192.168.3.254
t=0 0
m=audio 7082 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:7083


___TRACE:___   Thu Jan  9 17:10:27 2014.114
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(183) Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:27 2014.116
SIP Signalling(2695): ./src/sg_engine.cpp:5601 EvProgress(ISipSessionSvc)

~~~DEBUG:~~~   Thu Jan  9 17:10:27 2014.117
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:27 2014.118
SIP Signalling(2492): ./src/sgCDispatcher.cpp:197 Engine->CallService: sipCallProgress

___TRACE:___   Thu Jan  9 17:10:27 2014.118
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1918 sgEvtCallProgress() - START: CallRefId(123) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:27 2014.118
SIP Signalling(2492): ./src/sgSdpService.cpp:583 SdpStateEventMachine() - CallRefId(123) CalLRefIdForCC(123) SdpState(1,SDP_OFFER_SENT) eTypeOfMessage(3) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.119
SIP Signalling(2492): ./src/sgCSipPacket.cpp:999 HasContentTypeHeader() - Content(application/sdp) available

___TRACE:___   Thu Jan  9 17:10:27 2014.119
SIP Signalling(2492): ./src/sgCSipPacket.cpp:918 GetFirstPayload() - application/sdp found

___TRACE:___   Thu Jan  9 17:10:27 2014.119
SIP Signalling(2492): ./src/sgSdpService.cpp:2389 GenerateRemoteCapsMgr() - CallRefId(123) CallRefIdForCC(123)

___TRACE:___   Thu Jan  9 17:10:27 2014.120
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:372 SetRemoteSdpOrigin() - m_bRemoteSdpOriginChanged set

___TRACE:___   Thu Jan  9 17:10:27 2014.122
SIP Signalling(2492): ./src/sgSdpService.cpp:3044 VerifySdpAnswer() - CallRefId(123) CalLRefIdForCC(123)

___TRACE:___   Thu Jan  9 17:10:27 2014.125
SIP Signalling(2492): ./src/sgSdpService.cpp:3071 VerifySdpAnswer() - OKAY, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:27 2014.125
SIP Signalling(2492): ./src/sgSdpService.cpp:3091 VerifySdpAnswer() - Media Supported res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:27 2014.125
SIP Signalling(2492): ./src/sgSdpService.cpp:3132 VerifySdpAnswer() - really OKAY, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgSdpService.cpp:3170 VerifySdpAnswer() - CallRefId(123) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) CurrentCallAction(0,eCALL_A_NULL) bHoldIndication(0) bHoldUsingSendOnly(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:431 CallState - Call(123) transition from (0,eCALL_NULL) to (1,eCALL_CONNECTED)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgSdpService.cpp:3604 GetRtpSession() - iPort(5004) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgSdpService.cpp:3617 GetRtpSession() - pReturnSession[0](0xabc2b0)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgSdpService.cpp:3630 GetRtpSession() - pReturnSession[0](0xabc2b0) - port(5004) iPort(5004) eMediaType(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.126
SIP Signalling(2492): ./src/sgSdpService.cpp:3684 SetSessionDirection() - CallRefId(123) CallState(1,eCALL_CONNECTED) RtpMediaType(0) eSessionDirection(5) bFromVerifyAnswer(1)

___TRACE:___   Thu Jan  9 17:10:27 2014.127
SIP Signalling(2492): ./src/sgCSdpCapabilitiesMgr.cpp:90 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Jan  9 17:10:27 2014.127
SIP Signalling(2492): ./src/sgSdpService.cpp:3448 VerifySdpAnswer() - no video supported, res(0,S:OK)

___TRACE:___   Thu Jan  9 17:10:27 2014.127
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched(0xabc2b0) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.127
SIP Signalling(2492): ./src/sgSdpService.cpp:3649 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched((nil)) eRtpMediaType(1)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:401 SdpState - Call(123) transition from (1,SDP_OFFER_SENT) to (4,SDP_ANSWER_RECEIVED)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(123) transition from (1,eCALL_P_INITIAL) to (2,eCALL_P_EARLY)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgSdpService.cpp:1436 SdpStateEventMachine() END - rCallRecord(123) rCallRecord.GetSdpStateStr(4,SDP_ANSWER_RECEIVED) eCallEvent(0) bIncludeSdp(1)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:678 SetRemoteUriDefault() - Call(123) Display() User(**610)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:838 RemoteCallSecurityIndication - Call(123) transition from (-2147483643,FE:Null pointer) to (2,SI:False)

___TRACE:___   Thu Jan  9 17:10:27 2014.128
SIP Signalling(2492): ./src/sgCSipPacket.cpp:747 GetRequestUri() - SipPacket is not a request

___TRACE:___   Thu Jan  9 17:10:27 2014.129
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5219 SIG->CC: SendDisplayUpdate()/sgCallUpdateDisplayID - CallRefId(123) CallRefIdForCC(123) exitConf(0)

~~~DEBUG:~~~   Thu Jan  9 17:10:27 2014.129
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:27 2014.129
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 25

___TRACE:___   Thu Jan  9 17:10:27 2014.129
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:618 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(123)

___TRACE:___   Thu Jan  9 17:10:27 2014.130
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:798 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Jan  9 17:10:27 2014.130
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:500 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Jan  9 17:10:27 2014.130
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:59 GetRtpSessionToBeUsed() - returns pRtpSession(0xabc2b0) SessionHandle(20) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:27 2014.131
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:324 sgSetRemoteSessionDescription: pRtpSession(0xabc2b0) SessionHandle(20) - <media-sessiondescription><remote-socket><ip-addr-v4>192.168.3.254</ip-addr-v4><port>7082</port><rtcp-port>0</rtcp-port></remote-socket><ptime>0</ptime><resource-priority>0</resource-priority><audio-silence-suppression>false</audio-silence-suppression><audio><audio-mode><codec>PCMA</codec><order>1</order></audio-mode></audio><dtmf-payload>101</dtmf-payload><qdc><subscriber-no>621</subscriber-no><subscriber-type>2</subscriber-type><call-id>1598bafb2bd8099e</call-id><hipath-switch-id>Unknown</hipath-switch-id></qdc></media-sessiondescription>

___TRACE:___   Thu Jan  9 17:10:27 2014.156
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:329 setRemoteSessionDescription - success:

___TRACE:___   Thu Jan  9 17:10:27 2014.156
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:398 sgSetSessionDirection() - eSessionDirection(0,eFromRtpSession)

___TRACE:___   Thu Jan  9 17:10:27 2014.156
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:410 sgSetSessionDirection() - pRtpSession(0xabc2b0): SessionHandle(20) SessionDirection(0,eSENDRECV)

___TRACE:___   Thu Jan  9 17:10:27 2014.208
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:443 setDirection - success

___TRACE:___   Thu Jan  9 17:10:27 2014.208
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2289 SIG->CC: sgEvtCallProgress()/58 - CallRefId(123) CallRefIdForCC(123) ResourcePriority(99) ResourcePriorityToCC(0)

~~~DEBUG:~~~   Thu Jan  9 17:10:27 2014.208
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:27 2014.208
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 58

___TRACE:___   Thu Jan  9 17:10:27 2014.208
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 123, newState = ccCallProgress, traceId = '')

___TRACE:___   Thu Jan  9 17:10:27 2014.208
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=123: Old state=ccCallProceeding: New state=ccCallProgress:

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.209
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:27 2014.210
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2297 sgEvtCallProgress() - END: CallRefId(123) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:27 2014.280
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_tracing.c:473 Line 1: E_FM_join:   S_FM_consultation_proceeding ---> S_FM_transfer_with_consultation :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 4 current_state 15 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 2 current_state 2 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.361
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_features.c:5108 ACT_transfer_with_consultation : 1511

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccConsultationCall : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_features.c:5152 ACT_transfer_with_consultation: Forwarding - secondary call reference : 123

___TRACE:___   Thu Jan  9 17:10:35 2014.362
SIP Call Control(3332): ./src/cc_features.c:5153 ACT_transfer_with_consultation: Forwarding - primary call reference : 1511

___TRACE:___   Thu Jan  9 17:10:35 2014.363
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 57; state = 2

___TRACE:___   Thu Jan  9 17:10:35 2014.363
SIP Signalling(2492): ./src/sgCDispatcher.cpp:686 CC->SIG:  sgCallJoin:

___TRACE:___   Thu Jan  9 17:10:35 2014.363
SIP Signalling(2492): ./src/sg_call_service_int.cpp:1602 sgJoin()/CallTransferA - CallRefId(1511) CallRefIdForCC(1511) CallState(2,eCALL_LOCAL_HOLD) CallSubState(0,eCALL_SS_NULL) transfer to CallRefId(123) CallRefIdForCC(123) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:35 2014.363
SIP Signalling(2492): ./src/sg_engine_transfer.cpp:77 CallTransferA()

___TRACE:___   Thu Jan  9 17:10:35 2014.364
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 19, 0xad4f00)

___TRACE:___   Thu Jan  9 17:10:35 2014.364
SIP Signalling(2695): ./src/sg_engine_transfer.cpp:108 InternalCallTransferA() - unCallRef_Transferee(1511), unCallRef_TransferTarget(123) naRemoteUri.GetSipUri((nil))

___TRACE:___   Thu Jan  9 17:10:35 2014.370
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
REFER sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK316673090de7946db
Max-Forwards: 70
From: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
To: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
Call-ID: [email protected]
CSeq: 1365532476 REFER
Contact: "621" <sip:[email protected]:5060;transport=udp>
Refer-To: <sip:[email protected]?Replaces=1598bafb2bd8099e%3bto-tag%3d7CE9230B7CB01DD1%3bfrom-tag%3d7d0f3912e8>
Referred-By: "621" <sip:[email protected]>
Supported: timer, 100rel, replaces
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.377
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 501 Not Implemented
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK316673090de7946db
From: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
To: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
Call-ID: [email protected]
CSeq: 1365532476 REFER
User-Agent: FRITZ!OS
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.378
SIP Signalling(2695): ./src/sg_engine_transfer.cpp:338 EvReferFailure(ISipTransferSvc07)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.379
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:35 2014.379
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 133; state = 2

___TRACE:___   Thu Jan  9 17:10:35 2014.379
SIP Signalling(2492): ./src/sgCDispatcher.cpp:265 Engine->CallService: sipCallTransferFailed

***ERROR:***   Thu Jan  9 17:10:35 2014.379
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:113 GetCallRecord() - cannot search for zero CallRefId

***ERROR:***   Thu Jan  9 17:10:35 2014.379
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:4338 sgEvtCallTransferFailed() - TransferToCallRefId(0) not found

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:4347 sgEvtCallTransferFailed() - CallRefId(1511) CallState(2,eCALL_LOCAL_HOLD) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:4383 SIG->CC: sgEvtCallTransferFailed()/sgCallForwardReject - CallRefId(1511) CallRefIdForCC(1511) failureReason(4)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.380
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 28

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_forward_reject:   S_FM_transfer_with_consultation ---> S_FM_transfer_failed :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 4 current_state 15 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 2 current_state 2 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.380
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.381
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.381
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.381
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccPrimaryCall : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.381
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccConsultationCall : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.382
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FML_FORCE: :   S_FM_transfer_failed ---> S_FM_consultation_proceeding :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.388
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(501) Method(REFER)

___TRACE:___   Thu Jan  9 17:10:35 2014.391
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bK559D052D0BA8F3CE
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
Call-ID: [email protected]
CSeq: 15 BYE
X-RTP-Stat: CS=169;PS=124;ES=176;OS=19840;SP=0/0;SO=0;QS=-;PR=166;ER=176;OR=26560;CR=0;SR=0;QR=-;PL=0,0;BL=0;LS=0;RB=0/0;SB=-/-;EN=PCMA;DE=PCMA;JI=1,0;DL=0,0,0;IP=192.168.3.254:7078,192.168.3.162:5006
X-RTP-Stat-Add: DQ=0;DSS=0;DS=0;PLCS=0;JS=0
Reason: Q.850; cause=16
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 7490 113.06.02 (Dec  6 2013)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.392
SIP Signalling(2695): ./src/sg_engine.cpp:4391 EvOnPacketReceived(BYE)

___TRACE:___   Thu Jan  9 17:10:35 2014.393
SIP Signalling(2695): ./src/sg_engine.cpp:6746 EvTerminated(ISipSessionSvc)

___TRACE:___   Thu Jan  9 17:10:35 2014.393
SIP Signalling(2695): ./src/sg_engine.cpp:6844 EvTerminated() - CallRefId(1511)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.394
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:35 2014.394
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 117; state = 2

___TRACE:___   Thu Jan  9 17:10:35 2014.394
SIP Signalling(2492): ./src/sgCDispatcher.cpp:167 Engine->CallService: sipCallTerminated

___TRACE:___   Thu Jan  9 17:10:35 2014.394
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:804 ExtractReasonData() - protocol(Q.850) not supported

___TRACE:___   Thu Jan  9 17:10:35 2014.394
SIP Signalling(2492): ./src/sgCSipPacket.cpp:637 GetRawHeader(12) - header not available

___TRACE:___   Thu Jan  9 17:10:35 2014.395
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:892 ExtractCallInfoRawHeader() - no RawHeader for Call-Info header available

___TRACE:___   Thu Jan  9 17:10:35 2014.395
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:59 GetRtpSessionToBeUsed() - returns pRtpSession(0xac2a90) SessionHandle(19) eRtpMediaType(0)

___TRACE:___   Thu Jan  9 17:10:35 2014.396
SIP Signalling(2696): ./src/sg_engine.cpp:9186 EvPacketReceived(ISipTransportObserver) - REQ Method(BYE)

___TRACE:___   Thu Jan  9 17:10:35 2014.399
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet In <---192.168.3.254
SIP/2.0 486 Busy Here
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK889b3186edbaa95f9
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>;tag=7CE9230B7CB01DD1
Call-ID: 1598bafb2bd8099e
CSeq: 1953235265 INVITE
User-Agent: FRITZ!OS
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.401
SIP Signalling(2695): ./src/sg_engine.cpp:5058 EvFailure() - CallRefId(123) bHidden(0) DialogSize(1)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.401
SIP Signalling(2695): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___   Thu Jan  9 17:10:35 2014.404
SIP Signalling(2696): ./src/sg_engine.cpp:9199 EvPacketReceived(ISipTransportObserver) - RES Code(486) Method(INVITE)

___TRACE:___   Thu Jan  9 17:10:35 2014.405
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
ACK sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.3.162;branch=z9hG4bK889b3186edbaa95f9
Max-Forwards: 70
From: "621" <sip:[email protected]>;tag=7d0f3912e8;epid=SC0310a3
To: <sip:**[email protected]:5060>;tag=7CE9230B7CB01DD1
Call-ID: 1598bafb2bd8099e
CSeq: 1953235265 ACK
User-Agent: OpenStage_80_V3 R1.49.0      SIP  131105
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1482 EXT: CallService->Engine: sgEvtCallTerminated()/ByeResponseA - CallRefId(1511) CallRefIdForCC(1511) Code(200) Reason(OK)

___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Signalling(2492): ./src/sg_engine.cpp:3852 ByeResponseA()

***ERROR:***   Thu Jan  9 17:10:35 2014.429
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:113 GetCallRecord() - cannot search for zero CallRefId

___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:1518 SIG->CC: sgEvtCallTerminated()/sgCallStateDisconnected - CallRefId(1511) CallRefIdForCC(1511)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.429
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 17

___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 1511

___TRACE:___   Thu Jan  9 17:10:35 2014.429
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 15

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_clear_remote:   S_CC_call_held ---> S_CC_call_cleared : 1511 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 1511, newState = ccCallCleared, traceId = '')

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=1511: Old state=ccCallOnHold: New state=ccCallCleared:

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.430
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_notify.c:90 Number of calls (decremented): 1

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_events.c:1951 ACT_call_cleared - : 1511

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_call_cleared:   S_FM_consultation_proceeding ---> S_FM_3pty_remote_cleared :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 4 current_state 11 callref 1511
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.434
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 2 current_state 2 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccHeld2Call : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_tracing.c:650 CALLREC FREE = 1511 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_tracing.c:685 Set calltype = ccPrimaryCall for callref 123 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FML_FORCE: :   S_FM_3pty_remote_cleared ---> S_FM_primary_call_wait_state :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.435
SIP Signalling(2492): ./src/sg_engine.cpp:12023 RemoveCallA() - unCallRefId(1511)

___TRACE:___   Thu Jan  9 17:10:35 2014.436
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:1322 StopRetryTimer() - stopping RetryTimer((nil)) at call(1511)

___TRACE:___   Thu Jan  9 17:10:35 2014.436
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:207 sgCloseSession() - closing pRtpSession(0xac2a90) with handle(19)

___TRACE:___   Thu Jan  9 17:10:35 2014.437
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 31, 0xad4f00)

___TRACE:___   Thu Jan  9 17:10:35 2014.437
SIP Signalling(2695): ./src/sg_engine.cpp:3871 InternalByeResponseA() - unCallRefId(1511), unLineId(1)

___TRACE:___   Thu Jan  9 17:10:35 2014.440
SIP Signalling(2695): ./src/sgCallInfoMgmt.cpp:74 RemoveCallInfo() - CallInfo(1511) removed, 1 CallInfo(s) left

___TRACE:___   Thu Jan  9 17:10:35 2014.440
SIP Signalling(2695): ./src/sg_engine.cpp:4287 CSipEngine(0xa95be0)::StopTimer(1511)

___TRACE:___   Thu Jan  9 17:10:35 2014.440
SIP Signalling(2695): ./src/sg_engine.cpp:4300 CSipEngine(0xa95be0)::StopTimerExit(0)

___TRACE:___   Thu Jan  9 17:10:35 2014.440
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 20, 0xa5a100)

___TRACE:___   Thu Jan  9 17:10:35 2014.440
SIP Signalling(2695): ./src/sg_engine.cpp:12044 InternalRemoveCallA() - unCallRefId(1511) bCalledFromExt(1)

***ERROR:***   Thu Jan  9 17:10:35 2014.441
SIP Signalling(2695): ./src/sgCallInfoMgmt.cpp:137 GetCallInfo() - CallInfo with CallRefId(1511) not found

___TRACE:___   Thu Jan  9 17:10:35 2014.441
SIP Signalling(2695): ./src/sg_engine.cpp:12053 InternalRemoveCallA() - No CallInfo or SipContext

___TRACE:___   Thu Jan  9 17:10:35 2014.443
SIP Messages(2696): ./src/sgCSipPacketLog.cpp:112 :

	Packet Out--->192.168.3.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.3.254:5060;branch=z9hG4bK559D052D0BA8F3CE
From: "Caller" <sip:[email protected]>;tag=48C339C36D04CC56
To: "621" <sip:[email protected]:5060>;expires=3600;tag=3878166149
Call-ID: [email protected]
CSeq: 15 BYE
Server: OpenStage_80_V3 R1.49.0      SIP  131105
Supported: 100rel, replaces, timer
Content-Length: 0



___TRACE:___   Thu Jan  9 17:10:35 2014.476
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

---INFO:---    Thu Jan  9 17:10:35 2014.501
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.501
ClockServiceInvoker(2270): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getDisplayTimeVal;3;req;

---INFO:---    Thu Jan  9 17:10:35 2014.509
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.509
ClockServiceInvoker(3332): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 11;getTimeInts;3;req;

___TRACE:___   Thu Jan  9 17:10:35 2014.509
ClockService(3332): ./src/ClockService.cpp:1639 getTimeInternal -> timeNow: 1389283835

___TRACE:___   Thu Jan  9 17:10:35 2014.509
ClockService(3332): ./src/ClockService.cpp:1642 getTimeInternal -> hours: 17, minutes: 10, seconds: 35

---INFO:---    Thu Jan  9 17:10:35 2014.513
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.515
ClockServiceInvoker(2690): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 11;getDateInts;3;req;

---INFO:---    Thu Jan  9 17:10:35 2014.541
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.547
ClockServiceInvoker(2690): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getUTCTimeTimeVal;3;req;21;getUTCTimeTimeValTime;1;1;1;1;10;1389287417;

___TRACE:___   Thu Jan  9 17:10:35 2014.682
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:35 2014.767
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:94 RemoveCallRecord() - CallRecord(1511,1511) removed, 1 CallRecord(s) left

___TRACE:___   Thu Jan  9 17:10:35 2014.768
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 124; state = 2

___TRACE:___   Thu Jan  9 17:10:35 2014.768
SIP Signalling(2492): ./src/sgCDispatcher.cpp:209 Engine->CallService: sipCallFailed

___TRACE:___   Thu Jan  9 17:10:35 2014.768
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:670 SetRemoteUriDefault() - exit Call(123) Display() User(**610)

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:2980 sgEvtCallFailed() - CallRefId(123) CallRefIdForCC(123) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) CallPhase(2,eCALL_P_EARLY) OriginalCallRefId(0) TransferToCallRefId(0)

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:3013 sgEvtCallFailed() - CallRefId(123) CallRefIdForCC(123) will be cleared

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:500 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sgCSipPacket.cpp:637 GetRawHeader(12) - header not available

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:892 ExtractCallInfoRawHeader() - no RawHeader for Call-Info header available

***ERROR:***   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:113 GetCallRecord() - cannot search for zero CallRefId

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sgCallServiceHelper.cpp:866 ExtractWarningCode() - no Warning header in SipPacket

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:3080 sgEvtCallFailed() - calling SendCallDisconnected()

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:5869 SIG->CC: SendCallDisconnected()/sgCallStateDisconnected - CallRefId(123) CallRefIdForCC(123) clearingCause(1)

~~~DEBUG:~~~   Thu Jan  9 17:10:35 2014.769
SIP Signalling(2492): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___   Thu Jan  9 17:10:35 2014.769
SIP Call Control(2492): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 17

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 123

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_events.c:807 Exit action not defined for state : 2

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_CC_call_clear_remote:   S_CC_call_proceeding ---> S_CC_call_cleared : 123 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 123, newState = ccCallCleared, traceId = '')

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.770
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.771
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.771
SIP Call Control(2492): ./src/cc_notify.c:823 Call ref=123: Old state=ccCallProceeding: New state=ccCallCleared:

___TRACE:___   Thu Jan  9 17:10:35 2014.771
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.771
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.771
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.772
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccHeldCall : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.775
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.775
SIP Call Control(2492): ./src/cc_notify.c:90 Number of calls (decremented): 0

___TRACE:___   Thu Jan  9 17:10:35 2014.775
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_events.c:1951 ACT_call_cleared - : 123

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_call_cleared:   S_FM_primary_call_wait_state ---> S_FM_primary_call_cleared :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 0 current_state 11 callref 123
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.776
SIP Call Control(2492): ./src/cc_tracing.c:650 CALLREC FREE = 123 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccIsolatedCall : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:10657 lockSIPLine(0xaab668, -1) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_tracing.c:473 Line 1: E_FM_wait:   S_FM_primary_call_cleared ---> S_FM_idle :  : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 1 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:2729 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Call Control(2492): ./src/cc_features.c:10680 unlockSIPLine(0xaab668) - retval 1 : 0

___TRACE:___   Thu Jan  9 17:10:35 2014.777
SIP Signalling(2492): ./src/sg_engine.cpp:12023 RemoveCallA() - unCallRefId(123)

___TRACE:___   Thu Jan  9 17:10:35 2014.778
SIP Signalling(2492): ./src/sgCallRecordNew.cpp:1322 StopRetryTimer() - stopping RetryTimer((nil)) at call(123)

___TRACE:___   Thu Jan  9 17:10:35 2014.778
SIP Signalling(2492): ./src/MediaSessionMgr.cpp:207 sgCloseSession() - closing pRtpSession(0xabc2b0) with handle(20)

___TRACE:___   Thu Jan  9 17:10:35 2014.780
SIP Signalling(2695): ./src/sg_engine.cpp:3962 EvMessageServiceMgrAwaken(0, 20, 0xacf5c8)

___TRACE:___   Thu Jan  9 17:10:35 2014.780
SIP Signalling(2695): ./src/sg_engine.cpp:12044 InternalRemoveCallA() - unCallRefId(123) bCalledFromExt(1)

___TRACE:___   Thu Jan  9 17:10:35 2014.780
SIP Signalling(2695): ./src/sg_engine.cpp:4231 CSipEngine(0xa95be0)::StartTimer(123, 0xa5a100)

___TRACE:___   Thu Jan  9 17:10:35 2014.780
SIP Signalling(2695): ./src/sg_engine.cpp:4273 CSipEngine(0xa95be0)::StartTimerExit(0)

---INFO:---    Thu Jan  9 17:10:35 2014.803
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.803
ClockServiceInvoker(3004): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 11;getTimeInts;3;req;

___TRACE:___   Thu Jan  9 17:10:35 2014.804
ClockService(3004): ./src/ClockService.cpp:1639 getTimeInternal -> timeNow: 1389283835

___TRACE:___   Thu Jan  9 17:10:35 2014.804
ClockService(3004): ./src/ClockService.cpp:1642 getTimeInternal -> hours: 17, minutes: 10, seconds: 35

---INFO:---    Thu Jan  9 17:10:35 2014.806
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.807
ClockServiceInvoker(2265): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 11;getDateInts;3;req;

---INFO:---    Thu Jan  9 17:10:35 2014.868
ClockServiceInvoker(2264): ./src/ClockServiceInvoker.cpp:26 ClockServiceInvoker initialised

___TRACE:___   Thu Jan  9 17:10:35 2014.869
ClockServiceInvoker(2691): ./src/ClockServiceInvoker.cpp:46 handleMessage called: 17;getUTCTimeTimeVal;3;req;21;getUTCTimeTimeValTime;1;1;1;1;10;1389287427;

___TRACE:___   Thu Jan  9 17:10:35 2014.989
MessagesPhonelet(2656): ./src/MessagesPhonelet.cpp:414 MessagesPhonelet::deliverEvent()

___TRACE:___   Thu Jan  9 17:10:36 2014.043
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:94 RemoveCallRecord() - CallRecord(123,123) removed, 0 CallRecord(s) left

___TRACE:___   Thu Jan  9 17:10:36 2014.044
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 18; state = 2

___TRACE:___   Thu Jan  9 17:10:36 2014.044
SIP Signalling(2492): ./src/sgCDispatcher.cpp:633 CC->SIG:  sgCallDisconnectedConfirm:

***ERROR:***   Thu Jan  9 17:10:36 2014.044
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:131 GetCallRecord() - CallRecord with CallRefId(1511) not found

***ERROR:***   Thu Jan  9 17:10:36 2014.044
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:78 sgFarCallClearConfirm() - no CallRecord for callRefId(1511) available

___TRACE:___   Thu Jan  9 17:10:36 2014.045
SIP Signalling(2492): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 18; state = 2

___TRACE:___   Thu Jan  9 17:10:36 2014.045
SIP Signalling(2492): ./src/sgCDispatcher.cpp:633 CC->SIG:  sgCallDisconnectedConfirm:

***ERROR:***   Thu Jan  9 17:10:36 2014.045
SIP Signalling(2492): ./src/sgCallRecordMgmt.cpp:131 GetCallRecord() - CallRecord with CallRefId(123) not found

***ERROR:***   Thu Jan  9 17:10:36 2014.045
SIP Signalling(2492): ./src/sg_call_service_ext.cpp:78 sgFarCallClearConfirm() - no CallRecord for callRefId(123) available

Das Problem besteht übrigens nur bei eingehenden Anrufen. Ausgehend klappt das verbinden ohne Probleme scheinbar

mini01 · 10 Jan 2014

Scheint, als ob das REFER für den Transfer von der Fritz!Box abgelehnt wird.

Warum ? Das kann man aus dem OpenStage-Trace leider nicht rauslesen ...

vice_pres · 10 Jan 2014

Ich habe AVM mal geschrieben. Aber schon lustig, dass es nur eingehende Telefonate betrifft. Ausgehend klappt das ganze ohne große Probleme. Da mein neues OS60 mittlerweile aber auch angekommen ist brauch ich nicht mehr im Blindflug (+Taschenlampe...) mit dem defekten Display des OS80 zu testen.

Da fällt mir was ein: Gabs die OS60 eigentlich auch mit Display Type 2 - also LED? Meins hat 1 (genau wie das defekte 80er) also Kathode...

trendchiller · 10 Jan 2014

Wo kann ich den Display Type denn auslesen ?

vice_pres · 10 Jan 2014

Bei V3 Firmware (afaik) steht das im General Info im Admin Bereich

Einrichtungsleitfaden um ein OpenStage an der Fritzbox anzumelden

Neuer User

Mitglied

Neuer User

Neuer User

Neuer User

Neuer User

Neuer User

Neuer User

Neuer User

Neuer User

Mitglied

Mitglied

Neuer User

Mitglied

Mitglied

Anhänge

Mitglied

Mitglied

Mitglied

Mitglied

Mitglied

Statistik des Forums