[Frage] Es geht weiter mit dem OS60 SIP und der Fritzbox

[US31994]

Hallo
Ich habe mal einen Trace gezogen, bin mir aber nicht sicher ob das richtig ist
Zu dem habe ich noch ein Bild bei gefügt.
Danke

Trace file

             
___TRACE:___   Thu Sep 17 08:31:48 2015.315
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:48 2015.315
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:48 2015.315
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:31:48 2015.315
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:31:48 2015.341
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
INVITE sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK391a24cf5aa0a431d
Route: <sip:192.168.160.254:5060;lr;transport=udp>
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>
Call-ID: ed0edfa2e68d345d
CSeq: 1940269436 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "620" <sip:[email protected]:5060;transport=udp>
Supported: replaces, 100rel
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 359

v=0
o=OpenStage-Line_0 311972620 1462097156 IN IP4 192.168.160.23
s=SIP Call
c=IN IP4 192.168.160.23
t=0 0
m=audio 5010 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=rtcp:5011 IN IP4 192.168.160.23
a=sendrecv


___TRACE:___   Thu Sep 17 08:31:48 2015.347
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK391a24cf5aa0a431d
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=5164B906092A3D4C
Call-ID: ed0edfa2e68d345d
CSeq: 1940269436 INVITE
WWW-Authenticate: Digest realm="fritz.box", nonce="9D242F94912C20CF"
User-Agent: FRITZ!OS
Content-Length: 0



___TRACE:___   Thu Sep 17 08:31:48 2015.349
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(401) Method(INVITE)

___TRACE:___   Thu Sep 17 08:31:48 2015.391
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(123) state(2)

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Signalling(2231): ./src/SIPService.cpp:1106 Engine->CallService: sipCallProceeding

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:5496 SIG->CC: sgEvtCallProceeding()/sgCallStateProceeding - RefId(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Signalling(2231): ./src/SIPService.cpp:50 sgSendMessageToCC()

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Call Control(2231): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 11

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Call Control(2231): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 172

___TRACE:___   Thu Sep 17 08:31:48 2015.395
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
ACK sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK391a24cf5aa0a431d
Route: <sip:192.168.160.254:5060;lr;transport=udp>
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=5164B906092A3D4C
Call-ID: ed0edfa2e68d345d
CSeq: 1940269436 ACK
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701
Content-Length: 0



___TRACE:___   Thu Sep 17 08:31:48 2015.396
SIP Signalling(3210): ./src/sg_engine_auth.cpp:78 EvCredentialsRequired()

___TRACE:___   Thu Sep 17 08:31:48 2015.397
SIP Signalling(3210): ./src/sg_engine_auth.cpp:187 EvCredentialsRequired() - LineId(1) RefId(172) InfoObj(Call)

___TRACE:___   Thu Sep 17 08:31:48 2015.392
SIP Call Control(2231): ./src/cc_events.c:780 Exit action not defined for state : 1

___TRACE:___   Thu Sep 17 08:31:48 2015.408
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_CC_call_proceeding:   S_CC_call_setup ---> S_CC_call_proceeding : 172 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.408
SIP Call Control(2231): ./src/cc_notify.c:804 **** ccCallStateChange - reached start **** (thisCall = 172, newState = ccCallProceeding, traceId = '')

___TRACE:___   Thu Sep 17 08:31:48 2015.408
SIP Call Control(2231): ./src/cc_notify.c:832 Call ref=172: Old state=ccCallSettingUp: New state=ccCallProceeding:

___TRACE:___   Thu Sep 17 08:31:48 2015.440
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:52 callStateChange state=sfiCallProceeding: (2) lineURI=620: reason=0: uri=**601: name=: commServiceCallId=172: Priority=99: Premption Cause=6: Preemption Text=: call-level=100: local-level=100:

___TRACE:___   Thu Sep 17 08:31:48 2015.440
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:48 2015.441
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:48 2015.441
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:31:48 2015.441
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:31:48 2015.453
SIP Signalling(3210): ./src/sg_engine_auth.cpp:282 EvCredentialsRequired() - request reissued

___TRACE:___   Thu Sep 17 08:31:48 2015.511
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
INVITE sip:**[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK2989eec5f145e1550
Route: <sip:192.168.160.254:5060;lr;transport=udp>
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>
Call-ID: ed0edfa2e68d345d
CSeq: 1940269437 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Authorization: Digest username="620",realm="fritz.box",nonce="9D242F94912C20CF",uri="sip:**[email protected]:5060;transport=udp",response="ea481f46da67919b6e626525329e9315"
Contact: "620" <sip:[email protected]:5060;transport=udp>
Supported: replaces, 100rel
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 359

v=0
o=OpenStage-Line_0 311972620 1462097156 IN IP4 192.168.160.23
s=SIP Call
c=IN IP4 192.168.160.23
t=0 0
m=audio 5010 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=rtcp:5011 IN IP4 192.168.160.23
a=sendrecv


___TRACE:___   Thu Sep 17 08:31:48 2015.527
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK2989eec5f145e1550
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>
Call-ID: ed0edfa2e68d345d
CSeq: 1940269437 INVITE
User-Agent: AVM FRITZ!Box 7490 113.06.36 (Sep  4 2015)
Content-Length: 0



___TRACE:___   Thu Sep 17 08:31:48 2015.529
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(100) Method(INVITE)

___TRACE:___   Thu Sep 17 08:31:48 2015.531
SIP Signalling(3210): ./src/sg_engine_call.cpp:2299 EvProgress(ISipSessionSvc)

___TRACE:___   Thu Sep 17 08:31:48 2015.531
SIP Signalling(3210): ./src/sg_engine_call.cpp:2373 EvProgress() - CallProgressE(172,0,1,0xa5ca20)

___TRACE:___   Thu Sep 17 08:31:48 2015.532
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:31:48 2015.533
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(83) state(2)

___TRACE:___   Thu Sep 17 08:31:48 2015.533
SIP Signalling(2231): ./src/SIPService.cpp:821 Engine->CallService: sipCallProgress

___TRACE:___   Thu Sep 17 08:31:48 2015.533
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:1951 sgEvtCallProgress() - START: RefId(172,0) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Sep 17 08:31:48 2015.534
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2008 sgEvtCallProgress() - END 100Trying (100)

___TRACE:___   Thu Sep 17 08:31:48 2015.543
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK2989eec5f145e1550
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=53D002FA1C762955
Call-ID: ed0edfa2e68d345d
CSeq: 1940269437 INVITE
Contact: <sip:[email protected]>
User-Agent: AVM FRITZ!Box 7490 113.06.36 (Sep  4 2015)
Content-Type: application/sdp
Content-Length:   248

v=0
o=user 9115452 9115452 IN IP4 192.168.160.254
s=SIP Call
c=IN IP4 192.168.160.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 Sep 17 08:31:48 2015.545
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(183) Method(INVITE)

___TRACE:___   Thu Sep 17 08:31:48 2015.547
SIP Signalling(3210): ./src/sg_engine_call.cpp:2299 EvProgress(ISipSessionSvc)

___TRACE:___   Thu Sep 17 08:31:48 2015.547
SIP Signalling(3210): ./src/sg_engine_call.cpp:2373 EvProgress() - CallProgressE(172,0,1,0xa299e0)

___TRACE:___   Thu Sep 17 08:31:48 2015.547
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:31:48 2015.548
SIP Signalling(2231): ./src/SIPService.cpp:821 Engine->CallService: sipCallProgress

___TRACE:___   Thu Sep 17 08:31:48 2015.548
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:1951 sgEvtCallProgress() - START: RefId(172,0) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Sep 17 08:31:48 2015.548
SIP Signalling(2231): ./src/sgSdpService.cpp:587 SdpStateEventMachine() - RefId(172) RefIdForCC(172) SdpState(1,SDP_OFFER_SENT) eTypeOfMessage(3) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.549
SIP Signalling(2231): ./src/sgCSipPacket.cpp:1185 HasContentTypeHeader() - Content(application/sdp) available

___TRACE:___   Thu Sep 17 08:31:48 2015.549
SIP Signalling(2231): ./src/sgCSipPacket.cpp:1104 GetFirstPayload() - application/sdp found

___TRACE:___   Thu Sep 17 08:31:48 2015.549
SIP Signalling(2231): ./src/sgSdpService.cpp:2483 GenerateRemoteCapsMgr() - RefId(172) RefIdForCC(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.552
SIP Signalling(2231): ./src/sgSdpService.cpp:3125 VerifySdpAnswer() - RefId(172) RefIdForCC(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.556
SIP Signalling(2231): ./src/sgSdpService.cpp:3151 VerifySdpAnswer() - OKAY, res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:1045 VerifySrtpAnswer()

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:1108 VerifySrtpAnswer() - bReturn(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgSdpService.cpp:3168 VerifySdpAnswer() - Media Supported res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgSdpService.cpp:3209 VerifySdpAnswer() - res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:86 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgSdpService.cpp:3247 VerifySdpAnswer() - RefId(172) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) CurrentCallAction(0,eCALL_A_NULL) bHoldIndication(0) bHoldUsingSendOnly(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:491 CallState - RefId(172) transition from(0,eCALL_NULL) to(1,eCALL_CONNECTED)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:919 GetRtpSession() - RefId(172) Port(5010) MediaType(0) m_pvRtpSessions(0xa36de8)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:933 GetRtpSession() - RefId(172) pReturnSession[0](0xa8b7b0)

___TRACE:___   Thu Sep 17 08:31:48 2015.557
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:948 GetRtpSession() - RefId(172) pReturnSession[0](0xa8b7b0) - Port(5010) iPort(5010) MediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.558
SIP Signalling(2231): ./src/sgSdpService.cpp:3725 SetSessionDirection() - RefId(172) CallState(1,eCALL_CONNECTED) RtpMediaType(0) eSessionDirection(5) bFromVerifyAnswer(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.558
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:86 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Sep 17 08:31:48 2015.558
SIP Signalling(2231): ./src/sgSdpService.cpp:3528 VerifySdpAnswer() - no video supported, res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.558
SIP Signalling(2231): ./src/sgSdpService.cpp:3690 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched(0xa8b7b0) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.558
SIP Signalling(2231): ./src/sgSdpService.cpp:3690 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched((nil)) eRtpMediaType(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.559
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:451 SdpState - RefId(172) transition from(1,SDP_OFFER_SENT) to(4,SDP_ANSWER_RECEIVED)

___TRACE:___   Thu Sep 17 08:31:48 2015.559
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:570 CallPhase - RefId(172) transition from(1,eCALL_P_INITIAL) to(2,eCALL_P_EARLY)

___TRACE:___   Thu Sep 17 08:31:48 2015.559
SIP Signalling(2231): ./src/sgSdpService.cpp:1462 SdpStateEventMachine() END - RefId(172) SdpState(4,SDP_ANSWER_RECEIVED) CallEvent(0) IncludeSdp(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.560
SIP Signalling(2231): ./src/sgCSipPacket.cpp:793 GetRequestUri() - SipPacket is not a request

___TRACE:___   Thu Sep 17 08:31:48 2015.560
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:748 SetRemoteUriDefault() - RefId(172) Display() User(**601)

___TRACE:___   Thu Sep 17 08:31:48 2015.560
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:890 RemoteCallSecurityIndication - RefId(172) transition from(-2147483643,FE:Null pointer) to(2,SI:False)

___TRACE:___   Thu Sep 17 08:31:48 2015.560
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:5626 SIG->CC: SendDisplayUpdate()/sgCallUpdateDisplayID - RefId(172) RefIdForCC(172) exitConf(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.560
SIP Signalling(2231): ./src/SIPService.cpp:50 sgSendMessageToCC()

___TRACE:___   Thu Sep 17 08:31:48 2015.561
SIP Call Control(2231): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 23

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:1488 remoteEndpointDetailsChanged(620,172,**601,,0)

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTACallManager.cpp:214 CSTACallManager::getCallForCallID - callID=

___TRACE:___   Thu Sep 17 08:31:48 2015.574
CSTA_Service(2235): ./src/CSTACallManager.cpp:239 CSTACallManager::getCallForCallID - returning NULL

~~~DEBUG:~~~   Thu Sep 17 08:31:48 2015.575
CSTA_Service(2235): ./src/CSTA_Call.cpp:99 CSTA_Call::setRemoteConnectionDeviceID - this = 0xa69790, remoteConnectionDeviceID = **601

___TRACE:___   Thu Sep 17 08:31:48 2015.576
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(620),Monitors(1) <?xml version="1.0" encoding="UTF-8"?><TransferedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><cause>transfer</cause><localConnectionInfo>connected</localConnectionInfo><transferredConnections><connectionListItem><newConnection><callID>172</callID></newConnection></connectionListItem><deviceIdentifier>620</deviceIdentifier></transferredConnections><primaryOldCall><callID>172</callID><deviceID>620</deviceID></primaryOldCall><transferringDevice><deviceIdentifier>**601</deviceIdentifier></transferringDevice><transferredToDevice><deviceIdentifier>**601</deviceIdentifier></transferredToDevice><userData/></TransferedEvent>

___TRACE:___   Thu Sep 17 08:31:48 2015.576
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.576
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.577
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=localDeviceID

___TRACE:___   Thu Sep 17 08:31:48 2015.577
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0x93ff98

___TRACE:___   Thu Sep 17 08:31:48 2015.578
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(localDeviceID),Monitors(6) <?xml version="1.0" encoding="UTF-8"?><TransferedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><cause>transfer</cause><localConnectionInfo>connected</localConnectionInfo><transferredConnections><connectionListItem><newConnection><callID>172</callID></newConnection></connectionListItem><deviceIdentifier>620</deviceIdentifier></transferredConnections><primaryOldCall><callID>172</callID><deviceID>620</deviceID></primaryOldCall><transferringDevice><deviceIdentifier>**601</deviceIdentifier></transferringDevice><transferredToDevice><deviceIdentifier>**601</deviceIdentifier></transferredToDevice><userData/><monitorCrossRefID>29</monitorCrossRefID></TransferedEvent>

___TRACE:___   Thu Sep 17 08:31:48 2015.578
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.578
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.579
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.579
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.580
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.580
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.581
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2048

___TRACE:___   Thu Sep 17 08:31:48 2015.613
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:626 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.613
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:806 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Sep 17 08:31:48 2015.613
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:491 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Sep 17 08:31:48 2015.613
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:60 GetRtpSessionToBeUsed() - returns pRtpSession(0xa8b7b0) SessionHandle(53) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.616
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:316 sgSetRemoteSessionDescription: pRtpSession(0xa8b7b0) SessionHandle(53) - <media-sessiondescription><remote-socket><ip-addr-v4>192.168.160.254</ip-addr-v4><port>7082</port><rtcp-port>7083</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>620</subscriber-no><subscriber-type>2</subscriber-type><call-id>ed0edfa2e68d345d</call-id><hipath-switch-id>Unknown</hipath-switch-id></qdc></media-sessiondescription>

___TRACE:___   Thu Sep 17 08:31:48 2015.637
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK2989eec5f145e1550
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=53D002FA1C762955
Call-ID: ed0edfa2e68d345d
CSeq: 1940269437 INVITE
Contact: <sip:[email protected]>
User-Agent: AVM FRITZ!Box 7490 113.06.36 (Sep  4 2015)
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:   248

v=0
o=user 9115452 9115452 IN IP4 192.168.160.254
s=SIP Call
c=IN IP4 192.168.160.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 Sep 17 08:31:48 2015.639
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(INVITE)

___TRACE:___   Thu Sep 17 08:31:48 2015.641
SIP Signalling(3210): ./src/sg_engine_call.cpp:2397 EvSuccess(ISipSessionSvc)

___TRACE:___   Thu Sep 17 08:31:48 2015.641
SIP Signalling(3210): ./src/sgInfoObj.cpp:291 SipDialogState - RefId(172) transition from(0) to(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.641
SIP Signalling(3210): ./src/sg_engine_call.cpp:2474 EvSuccess() - first 200OK received for this Call(172) DialogSize(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.641
SIP Signalling(3210): ./src/sg_engine_call.cpp:2478 EvSuccess() - CallSuccessE(172,0,1,0xabc560)

___TRACE:___   Thu Sep 17 08:31:48 2015.641
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:31:48 2015.643
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:321 setRemoteSessionDescription - success:

___TRACE:___   Thu Sep 17 08:31:48 2015.643
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:390 sgSetSessionDirection() - eSessionDirection(0,eFromRtpSession)

___TRACE:___   Thu Sep 17 08:31:48 2015.644
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:402 sgSetSessionDirection() - pRtpSession(0xa8b7b0): SessionHandle(53) SessionDirection(0,eSENDRECV)

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:435 setDirection - success

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2341 SIG->CC: sgEvtCallProgress()/38 - RefId(172) RefIdForCC(172) ResourcePriority(99) ResourcePriorityToCC(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Signalling(2231): ./src/SIPService.cpp:50 sgSendMessageToCC()

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Call Control(2231): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 38

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Call Control(2231): ./src/cc_notify.c:804 **** ccCallStateChange - reached start **** (thisCall = 172, newState = ccCallProgress, traceId = '')

___TRACE:___   Thu Sep 17 08:31:48 2015.768
SIP Call Control(2231): ./src/cc_notify.c:832 Call ref=172: Old state=ccCallProceeding: New state=ccCallProgress:

___TRACE:___   Thu Sep 17 08:31:48 2015.769
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2349 sgEvtCallProgress() - END: RefId(172) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Sep 17 08:31:48 2015.770
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(84) state(2)

___TRACE:___   Thu Sep 17 08:31:48 2015.770
SIP Signalling(2231): ./src/SIPService.cpp:827 Engine->CallService: sipCallSuccess

___TRACE:___   Thu Sep 17 08:31:48 2015.771
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2493 sgEvtCallSuccess() - START: RefId(172) OriginalRefId(0) TransferToRefId(0) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Sep 17 08:31:48 2015.771
SIP Signalling(2231): ./src/sgSdpService.cpp:587 SdpStateEventMachine() - RefId(172) RefIdForCC(172) SdpState(4,SDP_ANSWER_RECEIVED) eTypeOfMessage(5) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.771
SIP Signalling(2231): ./src/sgCSipPacket.cpp:1185 HasContentTypeHeader() - Content(application/sdp) available

___TRACE:___   Thu Sep 17 08:31:48 2015.771
SIP Signalling(2231): ./src/sgCSipPacket.cpp:1104 GetFirstPayload() - application/sdp found

___TRACE:___   Thu Sep 17 08:31:48 2015.772
SIP Signalling(2231): ./src/sgSdpService.cpp:2483 GenerateRemoteCapsMgr() - RefId(172) RefIdForCC(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.776
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:52 callStateChange state=sfiCallProgress: (3) lineURI=620: reason=0: uri=**601: name=: commServiceCallId=172: Priority=0: Premption Cause=6: Preemption Text=: call-level=100: local-level=100:

___TRACE:___   Thu Sep 17 08:31:48 2015.776
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:48 2015.776
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:48 2015.776
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:31:48 2015.776
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:31:48 2015.778
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(620),Monitors(1) <?xml version="1.0" encoding="UTF-8"?><NetworkReachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions><privateData><private xmlns:scx="http://www.siemens.com/schema/csta"><scx:priority>0</scx:priority><scx:preemptionCause>6</scx:preemptionCause><scx:preemptionReason/><scx:ILLocalLevel>100</scx:ILLocalLevel><scx:ILCallLevel>100</scx:ILCallLevel><scx:CallBackAllowed>false</scx:CallBackAllowed></private></privateData></extensions><outboundConnection><callID>172</callID></outboundConnection><servicesPermitted><callAssociatedServices><generateDigits>true</generateDigits></callAssociatedServices></servicesPermitted><alertingDevice><deviceIdentifier>**601</deviceIdentifier></alertingDevice></NetworkReachedEvent>

___TRACE:___   Thu Sep 17 08:31:48 2015.778
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.778
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 0

___TRACE:___   Thu Sep 17 08:31:48 2015.797
SIP Signalling(2231): ./src/sgSdpService.cpp:3125 VerifySdpAnswer() - RefId(172) RefIdForCC(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.799
SIP Signalling(2231): ./src/sgSdpService.cpp:3151 VerifySdpAnswer() - OKAY, res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.799
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:1045 VerifySrtpAnswer()

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:1108 VerifySrtpAnswer() - bReturn(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgSdpService.cpp:3168 VerifySdpAnswer() - Media Supported res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgSdpService.cpp:3209 VerifySdpAnswer() - res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:86 GetSupportedStream() - eMediaType(0) ruStreamIndex(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgSdpService.cpp:3247 VerifySdpAnswer() - RefId(172) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) CurrentCallAction(0,eCALL_A_NULL) bHoldIndication(0) bHoldUsingSendOnly(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.821
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:919 GetRtpSession() - RefId(172) Port(5010) MediaType(0) m_pvRtpSessions(0xa36de8)

___TRACE:___   Thu Sep 17 08:31:48 2015.822
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:933 GetRtpSession() - RefId(172) pReturnSession[0](0xa8b7b0)

___TRACE:___   Thu Sep 17 08:31:48 2015.822
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:948 GetRtpSession() - RefId(172) pReturnSession[0](0xa8b7b0) - Port(5010) iPort(5010) MediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.822
SIP Signalling(2231): ./src/sgSdpService.cpp:3725 SetSessionDirection() - RefId(172) CallState(1,eCALL_CONNECTED) RtpMediaType(0) eSessionDirection(5) bFromVerifyAnswer(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.822
SIP Signalling(2231): ./src/sgCSdpCapabilitiesMgr.cpp:86 GetSupportedStream() - eMediaType(1) ruStreamIndex(-1)

___TRACE:___   Thu Sep 17 08:31:48 2015.849
SIP Signalling(2231): ./src/sgSdpService.cpp:3528 VerifySdpAnswer() - no video supported, res(0,S:OK)

___TRACE:___   Thu Sep 17 08:31:48 2015.849
SIP Signalling(2231): ./src/sgSdpService.cpp:3690 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched(0xa8b7b0) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.850
SIP Signalling(2231): ./src/sgSdpService.cpp:3690 RemoveRemoteSessionInformation() - pRtpSessionNotToBeTouched((nil)) eRtpMediaType(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.851
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:570 CallPhase - RefId(172) transition from(2,eCALL_P_EARLY) to(3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Sep 17 08:31:48 2015.851
SIP Signalling(2231): ./src/sgSdpService.cpp:1462 SdpStateEventMachine() END - RefId(172) SdpState(4,SDP_ANSWER_RECEIVED) CallEvent(0) IncludeSdp(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.851
SIP Signalling(2231): ./src/sgCSipPacket.cpp:793 GetRequestUri() - SipPacket is not a request

___TRACE:___   Thu Sep 17 08:31:48 2015.851
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:738 SetRemoteUriDefault() - RefId(172) Display() User(**601)

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:890 RemoteCallSecurityIndication - RefId(172) transition from(2,SI:False) to(2,SI:False)

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:626 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(172)

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:806 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgSdpService.cpp:587 SdpStateEventMachine() - RefId(172) RefIdForCC(172) SdpState(4,SDP_ANSWER_RECEIVED) eTypeOfMessage(6) bIncludeSdp(1) bResetCaps(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgSdpService.cpp:1174 SdpStateEventMachine() - INVITE_ACK_OUTGOING: NULL

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:335 SetCapsToUseForSdp() - RefId(172) using eCAPS_MGR_NULL for SDP

___TRACE:___   Thu Sep 17 08:31:48 2015.852
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:451 SdpState - RefId(172) transition from(4,SDP_ANSWER_RECEIVED) to(5,SDP_NEGOTIATION_FINISHED)

___TRACE:___   Thu Sep 17 08:31:48 2015.918
SIP Signalling(2231): ./src/sgSdpService.cpp:1462 SdpStateEventMachine() END - RefId(172) SdpState(5,SDP_NEGOTIATION_FINISHED) CallEvent(0) IncludeSdp(1)

___TRACE:___   Thu Sep 17 08:31:48 2015.918
SIP Signalling(2231): ./src/sgSdpService.cpp:195 GetSdp() - RefId(172) RefIdForCC(172) bIncludeSdp(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.918
SIP Signalling(2231): ./src/sg_engine_call.cpp:470 AcknowledgeA()

___TRACE:___   Thu Sep 17 08:31:48 2015.920
SIP Signalling(3210): ./src/sg_engine.cpp:1509 EvMessageServiceMgrAwaken(0,13,0xa0bd18)

___TRACE:___   Thu Sep 17 08:31:48 2015.920
SIP Signalling(3210): ./src/sg_engine_call.cpp:482 InternalAcknowledgeA()

___TRACE:___   Thu Sep 17 08:31:48 2015.920
SIP Signalling(3210): ./src/sg_engine_call.cpp:521 InternalAcknowledgeA() - RefId(172,0) LineId(1) IsProvisionalAcknowledge(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.927
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:60 GetRtpSessionToBeUsed() - returns pRtpSession(0xa8b7b0) SessionHandle(53) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.928
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:316 sgSetRemoteSessionDescription: pRtpSession(0xa8b7b0) SessionHandle(53) - <media-sessiondescription><remote-socket><ip-addr-v4>192.168.160.254</ip-addr-v4><port>7082</port><rtcp-port>7083</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>620</subscriber-no><subscriber-type>2</subscriber-type><call-id>ed0edfa2e68d345d</call-id><hipath-switch-id>Unknown</hipath-switch-id></qdc></media-sessiondescription>

___TRACE:___   Thu Sep 17 08:31:48 2015.944
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK92d4bf3e5a620f383
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=53D002FA1C762955
Call-ID: ed0edfa2e68d345d
CSeq: 1940269437 ACK
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Authorization: Digest username="620",realm="fritz.box",nonce="9D242F94912C20CF",uri="sip:**[email protected]:5060;transport=udp",response="ea481f46da67919b6e626525329e9315"
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701
Content-Length: 0



___TRACE:___   Thu Sep 17 08:31:48 2015.953
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:321 setRemoteSessionDescription - success:

___TRACE:___   Thu Sep 17 08:31:48 2015.953
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:491 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Sep 17 08:31:48 2015.953
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:390 sgSetSessionDirection() - eSessionDirection(0,eFromRtpSession)

___TRACE:___   Thu Sep 17 08:31:48 2015.953
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:402 sgSetSessionDirection() - pRtpSession(0xa8b7b0): SessionHandle(53) SessionDirection(0,eSENDRECV)

___TRACE:___   Thu Sep 17 08:31:48 2015.956
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:435 setDirection - success

___TRACE:___   Thu Sep 17 08:31:48 2015.956
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:6167 SetLocalStream() - RefId(172) RefIdForCC(172) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL) SdpState(5,SDP_NEGOTIATION_FINISHED) CallPhase(3,eCALL_P_ESTABLISHED)

___TRACE:___   Thu Sep 17 08:31:48 2015.956
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:60 GetRtpSessionToBeUsed() - returns pRtpSession(0xa8b7b0) SessionHandle(53) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:31:48 2015.956
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2990 SIG->CC: sgEvtCallSuccess()/sgCallStateConnected - RefId(172) RefIdForCC(172) ResourcePriority(99) ResourcePriorityToCC(0) CallLevel(100) LocalLevel(100)

___TRACE:___   Thu Sep 17 08:31:48 2015.956
SIP Signalling(2231): ./src/SIPService.cpp:50 sgSendMessageToCC()

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 15

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 172

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_events.c:780 Exit action not defined for state : 2

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_CC_call_connected:   S_CC_call_proceeding ---> S_CC_call_connected : 172 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_events.c:1502 ACT_call_connected : 172

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_events.c:1539 ACT_call_connected (172) setting SIPCallID(ed0edfa2e68d345d)

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_FM_call_connected:   S_FM_primary_call_wait_state ---> S_FM_primary_call_connected :  : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 1 calltype 0 current_state 7 callref 172
 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.957
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccHeldPartyFollowOn : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccIsolatedCall : 0

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_notify.c:804 **** ccCallStateChange - reached start **** (thisCall = 172, newState = ccCallConnected, traceId = '')

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_notify.c:832 Call ref=172: Old state=ccCallProceeding: New state=ccCallConnected:

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_notify.c:836 Not joinable = 2

___TRACE:___   Thu Sep 17 08:31:48 2015.958
SIP Call Control(2231): ./src/cc_notify.c:985 **** ccCallStateChange - reached ccCallConnected **** (thisCall = 172, newState =ccCallConnected)

___TRACE:___   Thu Sep 17 08:31:48 2015.962
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:2997 sgEvtCallSuccess() - END: RefId(172) CallState(1,eCALL_CONNECTED) CallSubState(0,eCALL_SS_NULL)

___TRACE:___   Thu Sep 17 08:31:48 2015.965
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:52 callStateChange state=sfiCallConnected: (8) lineURI=620: reason=0: uri=**601: name=: commServiceCallId=172: Priority=0: Premption Cause=6: Preemption Text=: call-level=100: local-level=100:

___TRACE:___   Thu Sep 17 08:31:48 2015.965
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:48 2015.965
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:48 2015.965
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:31:48 2015.965
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:31:48 2015.966
CSTA_Service(2235): ./src/CSTADevice.cpp:408 AddCallToDevice - call 172 [0xa69790] NOT added to 620 (already in list)

___TRACE:___   Thu Sep 17 08:31:48 2015.967
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(620),Monitors(1) <?xml version="1.0" encoding="UTF-8"?><EstablishedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><localConnectionInfo>connected</localConnectionInfo><cause>normal</cause><extensions><privateData><private xmlns:scx="http://www.siemens.com/schema/csta"><scx:inConference>false</scx:inConference><scx:priority>0</scx:priority><scx:ILLocalLevel>100</scx:ILLocalLevel><scx:ILCallLevel>100</scx:ILCallLevel></private></privateData></extensions><establishedConnection><callID>172</callID><deviceID>**601</deviceID></establishedConnection><answeringDevice><deviceIdentifier>**601</deviceIdentifier></answeringDevice><callingDevice><deviceIdentifier>620</deviceIdentifier></callingDevice><calledDevice><deviceIdentifier>**601</deviceIdentifier></calledDevice></EstablishedEvent>

___TRACE:___   Thu Sep 17 08:31:48 2015.967
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.967
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.969
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=localDeviceID

___TRACE:___   Thu Sep 17 08:31:48 2015.969
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0x93ff98

___TRACE:___   Thu Sep 17 08:31:48 2015.970
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(localDeviceID),Monitors(6) <?xml version="1.0" encoding="UTF-8"?><EstablishedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><localConnectionInfo>connected</localConnectionInfo><cause>normal</cause><extensions><privateData><private xmlns:scx="http://www.siemens.com/schema/csta"><scx:inConference>false</scx:inConference><scx:priority>0</scx:priority><scx:ILLocalLevel>100</scx:ILLocalLevel><scx:ILCallLevel>100</scx:ILCallLevel></private></privateData></extensions><establishedConnection><callID>172</callID><deviceID>**601</deviceID></establishedConnection><answeringDevice><deviceIdentifier>**601</deviceIdentifier></answeringDevice><callingDevice><deviceIdentifier>620</deviceIdentifier></callingDevice><calledDevice><deviceIdentifier>**601</deviceIdentifier></calledDevice><monitorCrossRefID>29</monitorCrossRefID></EstablishedEvent>

___TRACE:___   Thu Sep 17 08:31:48 2015.970
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.970
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.971
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.971
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.986
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.986
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:31:48 2015.993
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 16

___TRACE:___   Thu Sep 17 08:31:49 2015.342
CSTA_Service(2670): ./src/CSTAServiceProxy.cpp:61 
CSTA_Service ServiceProxy, result of parsing the incoming request in XML: (<?xml version="1.0" encoding="UTF-8"?><SetLampMode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><lampMode>2</lampMode><lampID>Hold</lampID><device>620</device></SetLampMode>)


___TRACE:___   Thu Sep 17 08:31:49 2015.346
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:48 <?xml version="1.0" encoding="UTF-8"?><SetLampMode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><lampMode>2</lampMode><lampID>Hold</lampID><device>620</device></SetLampMode>

___TRACE:___   Thu Sep 17 08:31:49 2015.347
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:49 2015.347
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:49 2015.351
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:93 <?xml version="1.0" encoding="UTF-8"?><SetLampModeResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>

___TRACE:___   Thu Sep 17 08:31:49 2015.417
CSTA_Service(3101): ./src/CSTAServiceProxy.cpp:61 
CSTA_Service ServiceProxy, result of parsing the incoming request in XML: (<?xml version="1.0" encoding="UTF-8"?><SetLampMode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><lampMode>2</lampMode><lampID>Hold</lampID><device>620</device></SetLampMode>)


___TRACE:___   Thu Sep 17 08:31:49 2015.420
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:48 <?xml version="1.0" encoding="UTF-8"?><SetLampMode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><lampMode>2</lampMode><lampID>Hold</lampID><device>620</device></SetLampMode>

___TRACE:___   Thu Sep 17 08:31:49 2015.420
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:31:49 2015.420
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:31:49 2015.424
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:93 <?xml version="1.0" encoding="UTF-8"?><SetLampModeResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>

___TRACE:___   Thu Sep 17 08:32:08 2015.666
CSTA_Service(2670): ./src/CSTAServiceProxy.cpp:61 
CSTA_Service ServiceProxy, result of parsing the incoming request in XML: (<?xml version="1.0" encoding="UTF-8"?><GenerateDigits xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><connectionToSendDigits><callID>172</callID><deviceID>620</deviceID></connectionToSendDigits><charactersToSend>2</charactersToSend></GenerateDigits>)


___TRACE:___   Thu Sep 17 08:32:08 2015.669
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:48 <?xml version="1.0" encoding="UTF-8"?><GenerateDigits xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><connectionToSendDigits><callID>172</callID><deviceID>620</deviceID></connectionToSendDigits><charactersToSend>2</charactersToSend></GenerateDigits>

___TRACE:___   Thu Sep 17 08:32:08 2015.670
CSTA_Service(2235): ./src/CSTACallManager.cpp:214 CSTACallManager::getCallForCallID - callID=172

___TRACE:___   Thu Sep 17 08:32:08 2015.670
CSTA_Service(2235): ./src/CSTACallManager.cpp:235 CSTACallManager::getCallForCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:32:08 2015.670
CSTA_Service(2235): ./src/CSTARequestAdaptorSIP.cpp:2165 CSTARequestAdaptor::__generateDigits - CommsServiceCallID=0x424c9c

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_tracing.c:473 Line 0: E_FM_send_out_of_band_info:   S_FM_primary_call_connected ---> S_FM_send_out_of_band_info :  : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 1 calltype 0 current_state 7 callref 172
 : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.674
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.675
SIP Call Control(2154): ./src/cc_tracing.c:473 Line 0: E_FML_REVERT: :   S_FM_send_out_of_band_info ---> S_FM_primary_call_connected :  : 0

___TRACE:___   Thu Sep 17 08:32:08 2015.676
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(10) state(2)

___TRACE:___   Thu Sep 17 08:32:08 2015.676
SIP Signalling(2231): ./src/SIPService.cpp:1291 CC->SIG: sgCallUserInformation

___TRACE:___   Thu Sep 17 08:32:08 2015.676
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:60 GetRtpSessionToBeUsed() - returns pRtpSession(0xa8b7b0) SessionHandle(53) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:32:08 2015.676
SIP Signalling(2231): ./src/sg_call_service_int.cpp:1665 sgUserInformation() - RefId(172) RefIdForCC(172) sending(2)

___TRACE:___   Thu Sep 17 08:32:08 2015.677
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:93 <?xml version="1.0" encoding="UTF-8"?><GenerateDigitsResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>

***ERROR:***   Thu Sep 17 08:32:34 2015.334
PhysicalInterfaceService(2122): ./src/PhysSubscriptionHelper.cpp:1078 distributeButtonEventToSubscribers - observer subscribed for function & key press (OVERLAP)

***ERROR:***   Thu Sep 17 08:32:34 2015.335
PhysicalInterfaceService(2122): ./src/PhysSubscriptionHelper.cpp:1087 Removed PhysicalEventObserver for CallViewPhonelet/Channel

___TRACE:___   Thu Sep 17 08:32:34 2015.349
CSTA_Service(2670): ./src/CSTAServiceProxy.cpp:61 
CSTA_Service ServiceProxy, result of parsing the incoming request in XML: (<?xml version="1.0" encoding="UTF-8"?><ClearConnection xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><cause>normalClearing</cause><connectionToBeCleared><callID>172</callID><deviceID>620</deviceID></connectionToBeCleared></ClearConnection>)


___TRACE:___   Thu Sep 17 08:32:34 2015.352
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:48 <?xml version="1.0" encoding="UTF-8"?><ClearConnection xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><cause>normalClearing</cause><connectionToBeCleared><callID>172</callID><deviceID>620</deviceID></connectionToBeCleared></ClearConnection>

___TRACE:___   Thu Sep 17 08:32:34 2015.354
CSTA_Service(2235): ./src/CSTARequestAdaptorSIP.cpp:2282 __ClearConnection CallID = 172

___TRACE:___   Thu Sep 17 08:32:34 2015.354
CSTA_Service(2235): ./src/CSTACallManager.cpp:214 CSTACallManager::getCallForCallID - callID=172

___TRACE:___   Thu Sep 17 08:32:34 2015.356
CSTA_Service(2235): ./src/CSTACallManager.cpp:235 CSTACallManager::getCallForCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:32:34 2015.357
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:32:34 2015.358
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:32:34 2015.358
CSTA_Service(2235): ./src/CSTADevice.cpp:432 RemoveCallFromDevice - call 0xa69790 removed from 620, nr of calls = 0

___TRACE:___   Thu Sep 17 08:32:34 2015.360
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:107 getNumberOfDevices - retval=1

___TRACE:___   Thu Sep 17 08:32:34 2015.360
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:131 getDeviceAtIndex - index=1, retval=0xa36988

___TRACE:___   Thu Sep 17 08:32:34 2015.361
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:399 CSTADeviceManager::CheckBusyStatus - All devices are idle

___TRACE:___   Thu Sep 17 08:32:34 2015.374
SIP Call Control(2154): ./src/cc_tracing.c:473 Line 0: E_FM_call_clear_local:   S_FM_primary_call_connected ---> S_FM_local_clear :  : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.374
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 1 calltype 0 current_state 7 callref 172
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.374
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.374
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.374
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.376
SIP Call Control(2154): ./src/cc_features.c:2621 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.377
SIP Call Control(2154): ./src/cc_features.c:8998 ./src/cc_features.c:8997 ACT_local_clear() - Line=0x7abffa08, callref=172 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.378
SIP Call Control(2154): ./src/cc_tracing.c:473 Line 0: E_FML_FORCE: :   S_FM_local_clear ---> S_FM_primary_call_wait_state :  : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.378
SIP Call Control(2154): ./src/cc_events.c:780 Exit action not defined for state : 7

___TRACE:___   Thu Sep 17 08:32:34 2015.379
SIP Call Control(2154): ./src/cc_tracing.c:473 Line 0: E_CC_call_clear_local:   S_CC_call_connected ---> S_CC_call_clearing : 172 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.380
SIP Call Control(2154): ./src/cc_notify.c:804 **** ccCallStateChange - reached start **** (thisCall = 172, newState = ccCallClearing, traceId = '')

___TRACE:___   Thu Sep 17 08:32:34 2015.380
SIP Call Control(2154): ./src/cc_notify.c:832 Call ref=172: Old state=ccCallConnected: New state=ccCallClearing:

___TRACE:___   Thu Sep 17 08:32:34 2015.383
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(9) state(2)

___TRACE:___   Thu Sep 17 08:32:34 2015.383
SIP Signalling(2231): ./src/SIPService.cpp:1241 CC->SIG: sgCallDisconnect

___TRACE:___   Thu Sep 17 08:32:34 2015.383
SIP Signalling(2231): ./src/sg_call_service_int.cpp:488 InternalLocalCallClear() - RefId(172,172) LineId(1/0) CallPhase(3) disconnectCause(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.383
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:60 GetRtpSessionToBeUsed() - returns pRtpSession(0xa8b7b0) SessionHandle(53) eRtpMediaType(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.388
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:491 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___   Thu Sep 17 08:32:34 2015.388
SIP Signalling(2231): ./src/sg_call_service_int.cpp:641 InternalLocalCallClear()/TerminateCallA - RefId(172) LineId(1) disconnectCause(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Signalling(2231): ./src/sg_engine_call.cpp:961 TerminateCallA()

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Signalling(2231): ./src/sg_call_service_int.cpp:694 SIG->CC: InternalLocalCallClear()/sgCallStateDisconnected - RefId(172) RefIdForCC(172) clearingCause(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Signalling(2231): ./src/SIPService.cpp:50 sgSendMessageToCC()

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Call Control(2231): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 16

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Call Control(2231): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef:  : 172

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Call Control(2231): ./src/cc_events.c:780 Exit action not defined for state : 9

___TRACE:___   Thu Sep 17 08:32:34 2015.389
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_CC_call_clear_remote:   S_CC_call_clearing ---> S_CC_call_cleared : 172 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.390
SIP Call Control(2231): ./src/cc_events.c:1932 ACT_call_cleared - ccPreviousEventState=9, ccCurrentState=5, clearingCause=0

___TRACE:___   Thu Sep 17 08:32:34 2015.390
SIP Call Control(2231): ./src/cc_notify.c:804 **** ccCallStateChange - reached start **** (thisCall = 172, newState = ccCallCleared, traceId = '')

___TRACE:___   Thu Sep 17 08:32:34 2015.390
SIP Call Control(2231): ./src/cc_notify.c:832 Call ref=172: Old state=ccCallClearing: New state=ccCallCleared:

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_notify.c:90 Number of calls (decremented): 0

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_events.c:1954 ACT_call_cleared - : 172

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_FM_call_cleared:   S_FM_primary_call_wait_state ---> S_FM_primary_call_cleared :  : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 1 calltype 0 current_state 11 callref 172
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.391
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_tracing.c:650 CALLREC FREE = 172 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_tracing.c:657 Failed to find callref for calltype: ccIsolatedCall : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_tracing.c:473 Line 0: E_FM_wait:   S_FM_primary_call_cleared ---> S_FM_idle :  : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 1 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.392
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 2 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.393
SIP Signalling(3210): ./src/sg_engine.cpp:1509 EvMessageServiceMgrAwaken(0,19,0xa3ea88)

___TRACE:___   Thu Sep 17 08:32:34 2015.393
SIP Signalling(3210): ./src/sg_engine_call.cpp:1008 InternalTerminateCallA() - RefId(172) LineId(1) Code(100) SipDialogState(1)

___TRACE:___   Thu Sep 17 08:32:34 2015.398
SIP Signalling(3210): ./src/sgInfoObj.cpp:291 SipDialogState - RefId(172) transition from(1) to(3)

___TRACE:___   Thu Sep 17 08:32:34 2015.398
SIP Signalling(3210): ./src/sg_engine_call.cpp:1275 InternalTerminateCallA() - RefId(172) LineId(1) SipDialogState(3)

___TRACE:___   Thu Sep 17 08:32:34 2015.401
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
BYE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK7e77611c80666841a
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=53D002FA1C762955
Call-ID: ed0edfa2e68d345d
CSeq: 1940269438 BYE
Authorization: Digest username="620",realm="fritz.box",nonce="9D242F94912C20CF",uri="sip:[email protected]",response="c463cca44aa80023c9c3b7666a295922"
Supported: 100rel, replaces
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701
X-Siemens-RTP-stats-list: MT=0;PS=2276;OS=363224;PR=2281;OR=364960;PL=0;JI=2;LA=1;SS=0;EN=8;DE=8;IE=00;TCLW=52
Content-Length: 0



___TRACE:___   Thu Sep 17 08:32:34 2015.414
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK7e77611c80666841a
From: "620" <sip:[email protected]:5060>;tag=d2db3c12a4;epid=SC0e408f
To: <sip:**[email protected]:5060>;tag=53D002FA1C762955
Call-ID: ed0edfa2e68d345d
CSeq: 1940269438 BYE
X-RTP-Stat: CS=0;PS=2074;ES=2293;OS=331840;SP=0/0;SO=0;QS=-;PR=2258;ER=2293;OR=361280;CR=0;SR=0;QR=-;PL=0,0;BL=0;LS=0;RB=0/0;SB=-/-;EN=PCMA;DE=PCMA;JI=183,0;DL=1,1,1;IP=192.168.160.254:7082,192.168.160.23:5010
X-RTP-Stat-Add: DQ=0;DSS=0;DS=0;PLCS=1920;JS=0
User-Agent: AVM FRITZ!Box 7490 113.06.36 (Sep  4 2015)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Content-Length: 0



___TRACE:___   Thu Sep 17 08:32:34 2015.416
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(BYE)

___TRACE:___   Thu Sep 17 08:32:34 2015.417
SIP Signalling(3210): ./src/sg_engine_call.cpp:2702 EvTerminationSuccess(ISipSessionSvc)

___TRACE:___   Thu Sep 17 08:32:34 2015.417
SIP Signalling(3210): ./src/sg_engine_call.cpp:2723 EvTerminationSuccess() - RefId(172) bHidden(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.417
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:32:34 2015.417
SIP Signalling(3210): ./src/sg_engine_call.cpp:1352 InternalRemoveInfoObjCall() - RefId(172) CloseRedundantConnections(0)

___TRACE:___   Thu Sep 17 08:32:34 2015.418
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:32:34 2015.418
SIP Signalling(3210): ./src/sgInfoObjMgmt.cpp:111 RemoveInfoObj() - RefId(172)/Call removed, 0 InfoObj(s) left

___TRACE:___   Thu Sep 17 08:32:34 2015.407
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 3 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.422
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 4 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.422
SIP Call Control(2231): ./src/cc_features.c:2621 Feature state change call leg 5 calltype 14 current_state 0 callref 0
 : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.422
SIP Call Control(2231): ./src/cc_sig_events.c:493 SendCallClearNotify - no dialog for line. Line: : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.422
SIP Call Control(2231): ./src/cc_events.c:2500 SendCallClearNotify - no dialog for line. Line: : 0

___TRACE:___   Thu Sep 17 08:32:34 2015.422
SIP Signalling(2231): ./src/sgRecordObjMgmtCall.cpp:153 GetRecordObjByRefId() - RefId(172,172) terminating, not returned

___TRACE:___   Thu Sep 17 08:32:34 2015.424
CSTA_Service(2235): ./src/CSTAServiceInvoker.cpp:93 <?xml version="1.0" encoding="UTF-8"?><ClearConnectionResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>

___TRACE:___   Thu Sep 17 08:32:34 2015.426
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:52 callStateChange state=sfiCallClearing: (12) lineURI=620: reason=0: uri=**601: name=: commServiceCallId=172: Priority=99: Premption Cause=6: Preemption Text=: call-level=100: local-level=100:

___TRACE:___   Thu Sep 17 08:32:34 2015.426
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:32:34 2015.426
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:32:34 2015.426
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:32:34 2015.426
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:32:34 2015.428
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:52 callStateChange state=sfiCallCleared: (13) lineURI=620: reason=0: uri=**601: name=: commServiceCallId=172: Priority=0: Premption Cause=6: Preemption Text=: call-level=100: local-level=100:

___TRACE:___   Thu Sep 17 08:32:34 2015.428
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:32:34 2015.428
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:32:34 2015.428
CSTA_Service(2235): ./src/CSTACallManager.cpp:253 CSTACallManager::getCallForCommsServiceCallID - callID=172

___TRACE:___   Thu Sep 17 08:32:34 2015.428
CSTA_Service(2235): ./src/CSTACallManager.cpp:273 CSTACallManager::getCallForCommsServiceCallID - returning 0xa69790 (172)

___TRACE:___   Thu Sep 17 08:32:34 2015.429
CSTA_Service(2235): ./src/CSTADevice.cpp:846 getNumberOfConnectedCallsOnDevice 620, connected numberOfCalls 0

___TRACE:___   Thu Sep 17 08:32:34 2015.430
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(620),Monitors(1) <?xml version="1.0" encoding="UTF-8"?><ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>0</monitorCrossRefID><droppedConnection><callID>172</callID><deviceIdentifier>620</deviceIdentifier></droppedConnection><releasingDevice><deviceIdentifier/></releasingDevice><lastRedirectionDevice><notRequired/></lastRedirectionDevice><localConnectionInfo>null</localConnectionInfo><cause>normal</cause><extensions><privateData><private xmlns:scx="http://www.siemens.com/schema/csta"><scx:preemptionCause>6</scx:preemptionCause><scx:preemptionReason/><scx:CallBackAllowed>false</scx:CallBackAllowed></private></privateData></extensions></ConnectionClearedEvent>

___TRACE:___   Thu Sep 17 08:32:34 2015.430
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.430
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.432
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=localDeviceID

___TRACE:___   Thu Sep 17 08:32:34 2015.432
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0x93ff98

___TRACE:___   Thu Sep 17 08:32:34 2015.447
CSTA_Service(2235): ./src/CSTADevice.cpp:187 Device(localDeviceID),Monitors(6) <?xml version="1.0" encoding="UTF-8"?><ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>29</monitorCrossRefID><droppedConnection><callID>172</callID><deviceIdentifier>620</deviceIdentifier></droppedConnection><releasingDevice><deviceIdentifier/></releasingDevice><lastRedirectionDevice><notRequired/></lastRedirectionDevice><localConnectionInfo>null</localConnectionInfo><cause>normal</cause><extensions><privateData><private xmlns:scx="http://www.siemens.com/schema/csta"><scx:preemptionCause>6</scx:preemptionCause><scx:preemptionReason/><scx:CallBackAllowed>false</scx:CallBackAllowed></private></privateData></extensions></ConnectionClearedEvent>

___TRACE:___   Thu Sep 17 08:32:34 2015.447
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.447
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.457
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.457
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.473
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.473
CSTA_Service(2235): ./src/CSTADevice.cpp:227 publishEvent: sending event for an application. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.493
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.493
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.493
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.493
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.493
CSTA_Service(2235): ./src/CSTADevice.cpp:219 publishEvent: using the filter of the actual monitor.

___TRACE:___   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTADevice.cpp:233 publishEvent: event is filtered out. EventGroup is 0, eventType is 2

___TRACE:___   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:70 getDeviceForDeviceID - deviceID=620

___TRACE:___   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTADeviceManager.cpp:94 getDeviceForDeviceID - retval=0xa36988

___TRACE:___   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTADevice.cpp:453 RemoveCallFromDevice - call 0xa69790 not found on 620

+++WARNING:+++ Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTAServiceCommsEventsSIP.cpp:187 Call 0xa69790 not found on device 620, but will be destroyed. This can point to an error.

___TRACE:___   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTACallManager.cpp:120 CSTACallManager::destroyCall - deleting call (CSTA callid=172, Comms callId=172)

~~~DEBUG:~~~   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTA_Call.cpp:77 Deleting CSTA_Call: this = 0xa69790 mCallID = 172

~~~DEBUG:~~~   Thu Sep 17 08:32:34 2015.494
CSTA_Service(2235): ./src/CSTAConferenceMembershipList.cpp:265 CSTAConferenceMembershipListManager::callCleared - lineURI=620, callId=172

___TRACE:___   Thu Sep 17 08:32:34 2015.495
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(17) state(2)

___TRACE:___   Thu Sep 17 08:32:34 2015.495
SIP Signalling(2231): ./src/SIPService.cpp:1248 CC->SIG: sgCallDisconnectedConfirm not needed anymore

___TRACE:___   Thu Sep 17 08:32:34 2015.495
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(79) state(2)

___TRACE:___   Thu Sep 17 08:32:34 2015.495
SIP Signalling(2231): ./src/SIPService.cpp:797 Engine->CallService: sipCallTerminationSuccess

___TRACE:___   Thu Sep 17 08:32:34 2015.495
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:806 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCSipPacket.cpp:683 GetRawHeader(12) - header not available

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:900 ExtractCallInfoRawHeader() - no RawHeader for Call-Info header available

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:626 PopulateResourcePriority() - no ResourcePriority header in SipPacket at call(172)

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:806 ExtractReasonData() - no Reason header in SipPacket

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCSipPacket.cpp:683 GetRawHeader(12) - header not available

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sgCallServiceHelper.cpp:900 ExtractCallInfoRawHeader() - no RawHeader for Call-Info header available

___TRACE:___   Thu Sep 17 08:32:34 2015.496
SIP Signalling(2231): ./src/sg_engine_call.cpp:1293 RemoveCallA() - RefId(172)

___TRACE:___   Thu Sep 17 08:32:34 2015.497
SIP Signalling(3210): ./src/sg_engine.cpp:1509 EvMessageServiceMgrAwaken(0,20,0xa3ea88)

___TRACE:___   Thu Sep 17 08:32:34 2015.497
SIP Signalling(3210): ./src/sg_engine_call.cpp:1327 InternalRemoveInfoObjCall() - no InfoObj for RefId(172)

___TRACE:___   Thu Sep 17 08:32:34 2015.497
SIP Signalling(2231): ./src/sgRecordObjCall.cpp:1448 StopRetryTimer() - RefId(172) stopping RetryTimer((nil))

___TRACE:___   Thu Sep 17 08:32:34 2015.497
SIP Signalling(2231): ./src/MediaSessionMgr.cpp:197 sgCloseSession() - closing pRtpSession(0xa8b7b0) with handle(53)

___TRACE:___   Thu Sep 17 08:32:34 2015.568
SIP Signalling(2231): ./src/sgRecordObjMgmtCall.cpp:103 RemoveRecordObj() - RefId(172,172) removed, 0 RecordObj(s) left

___TRACE:___   Thu Sep 17 08:32:34 2015.569
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(124) state(2)

___TRACE:___   Thu Sep 17 08:32:34 2015.569
SIP Signalling(2231): ./src/SIPService.cpp:1112 Engine->CallService: sipCallRemoved

___TRACE:___   Thu Sep 17 08:32:34 2015.569
SIP Signalling(2231): ./src/sg_call_service_ext.cpp:5520 sgEvtCallRemoved() - RecordObj(172) not available

***ERROR:***   Thu Sep 17 08:32:34 2015.754
PhysicalInterfaceService(2122): ./src/PhysSubscriptionHelper.cpp:1078 distributeButtonEventToSubscribers - observer subscribed for function & key press (OVERLAP)

***ERROR:***   Thu Sep 17 08:32:34 2015.754
PhysicalInterfaceService(2122): ./src/PhysSubscriptionHelper.cpp:1087 Removed PhysicalEventObserver for CallViewPhonelet/Channel

___TRACE:___   Thu Sep 17 08:33:55 2015.793
SIP Signalling(3210): ./src/sg_engine_reg.cpp:953 EvExpiring(ISipRegistrationSvc)

___TRACE:___   Thu Sep 17 08:33:55 2015.793
SIP Signalling(3210): ./src/sg_engine_reg.cpp:970 EvExpiring() - RegistrationExpiringE(1,1)

___TRACE:___   Thu Sep 17 08:33:55 2015.793
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:33:55 2015.795
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(72) state(2)

___TRACE:___   Thu Sep 17 08:33:55 2015.795
SIP Signalling(2231): ./src/SIPService.cpp:752 Engine->RegService: sipRegistrationExpiring

___TRACE:___   Thu Sep 17 08:33:55 2015.795
SIP Signalling(2231): ./src/sg_reg_service.cpp:1304 sgEvtRegistrationExpiring() - LineId(1) Terminating(0) RegState(3)

___TRACE:___   Thu Sep 17 08:33:55 2015.795
SIP Signalling(2231): ./src/sgRecordObjReg.cpp:114 GetNextActionFromQueue(2,0)

___TRACE:___   Thu Sep 17 08:33:55 2015.795
SIP Signalling(2231): ./src/sgRecordObjReg.cpp:128 CurrentRegAction - LineId(1/0) transition from(0) to(2)

___TRACE:___   Thu Sep 17 08:33:55 2015.796
SIP Signalling(2231): ./src/sg_reg_service.cpp:478 sgMonitorRegistrations() - LineId(1)

___TRACE:___   Thu Sep 17 08:33:55 2015.796
SIP Signalling(2231): ./src/sg_reg_service.cpp:1607 RegService->Engine: SendRegisterRequestToEngine()/RegisterA - LineId(1) NewContext(0)

___TRACE:___   Thu Sep 17 08:33:55 2015.799
SIP Signalling(3210): ./src/sg_engine.cpp:1509 EvMessageServiceMgrAwaken(0,8,0xa3ea88)

___TRACE:___   Thu Sep 17 08:33:55 2015.800
SIP Signalling(3210): ./src/sg_engine_reg.cpp:174 InternalRegisterA() - LineId(1)

___TRACE:___   Thu Sep 17 08:33:55 2015.800
SIP Signalling(3210): ./src/sg_engine_reg.cpp:358 InternalRegisterA() - AddLocalRegistration LineId(1) Expiration(3600) WeakerContactMatching(0)

___TRACE:___   Thu Sep 17 08:33:55 2015.813
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
REGISTER sip:192.168.160.254:5060;transport=udp SIP/2.0
Accept: application/dls-contact-me
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK0c1601a3fd24fba17
Route: <sip:192.168.160.254:5060;lr;transport=udp>
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=68307ed98a;epid=SC0e408f
To: <sip:[email protected]:5060>
Call-ID: 3553c841529b1d3e
CSeq: 1562314144 REGISTER
Authorization: Digest username="620",realm="fritz.box",nonce="266B3511B570F4C0",uri="sip:192.168.160.254:5060;transport=udp",response="bccdf481268b1c44c89378cc8ff42e3c"
Contact: "620" <sip:[email protected]:5060;transport=udp>;expires=3600
Supported: X-Siemens-Proxy-State
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701 simple-uaCSTA
X-Siemens-IID: 802MAC=001ae80e408f
Content-Length: 0



___TRACE:___   Thu Sep 17 08:33:55 2015.819
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bK0c1601a3fd24fba17
From: "620" <sip:[email protected]:5060>;tag=68307ed98a;epid=SC0e408f
To: <sip:[email protected]:5060>;tag=624D785E19F9C18D
Call-ID: 3553c841529b1d3e
CSeq: 1562314144 REGISTER
WWW-Authenticate: Digest realm="fritz.box", nonce="30F9DBB8C1CC76CC"
User-Agent: FRITZ!OS
Content-Length: 0



___TRACE:___   Thu Sep 17 08:33:55 2015.821
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(401) Method(REGISTER)

___TRACE:___   Thu Sep 17 08:33:55 2015.822
SIP Signalling(3210): ./src/sg_engine_auth.cpp:314 EvCredentialsExist()

___TRACE:___   Thu Sep 17 08:33:55 2015.829
SIP Signalling(3210): ./src/sg_engine_auth.cpp:353 EvCredentialsExist() - request was reissued

___TRACE:___   Thu Sep 17 08:33:55 2015.833
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet Out--->192.168.160.254
REGISTER sip:192.168.160.254:5060;transport=udp SIP/2.0
Accept: application/dls-contact-me
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bKf2cdd6d500b24bc41
Route: <sip:192.168.160.254:5060;lr;transport=udp>
Max-Forwards: 70
From: "620" <sip:[email protected]:5060>;tag=68307ed98a;epid=SC0e408f
To: <sip:[email protected]:5060>
Call-ID: 3553c841529b1d3e
CSeq: 1562314145 REGISTER
Authorization: Digest username="620",realm="fritz.box",nonce="30F9DBB8C1CC76CC",uri="sip:192.168.160.254:5060;transport=udp",response="4e1891e7951fe6ab28fcd2389a6f1df5"
Contact: "620" <sip:[email protected]:5060;transport=udp>;expires=3600
Supported: X-Siemens-Proxy-State
User-Agent: OpenStage_60_V3 R3.40.1      SIP  150701 simple-uaCSTA
X-Siemens-IID: 802MAC=001ae80e408f
Content-Length: 0



___TRACE:___   Thu Sep 17 08:33:55 2015.839
SIP Messages(3211): ./src/sgCSipPacketLog.cpp:110 :

	Packet In <---192.168.160.254
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.160.23;branch=z9hG4bKf2cdd6d500b24bc41
From: "620" <sip:[email protected]:5060>;tag=68307ed98a;epid=SC0e408f
To: <sip:[email protected]:5060>;tag=E05F29760320340A
Call-ID: 3553c841529b1d3e
CSeq: 1562314145 REGISTER
Contact: "620" <sip:[email protected]:5060;transport=udp>;expires=300
User-Agent: AVM FRITZ!Box 7490 113.06.36 (Sep  4 2015)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer,reg
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0



___TRACE:___   Thu Sep 17 08:33:55 2015.849
SIP Signalling(3210): ./src/sg_engine_reg.cpp:695 EvSuccess(ISipRegistrationSvc) - unStatusCode(200)

___TRACE:___   Thu Sep 17 08:33:55 2015.849
SIP Signalling(3210): ./src/sg_engine_reg.cpp:792 EvSuccess() - RegisterSuccessE(1,1,0xa8ab68) Terminating(0)

___TRACE:___   Thu Sep 17 08:33:55 2015.849
SIP Signalling(3210): ./src/SIPService.cpp:389 dispatchMsgToSignalling()

___TRACE:___   Thu Sep 17 08:33:55 2015.851
SIP Signalling(2231): ./src/SIPService.cpp:720 sgParseIncomingMessage - event(70) state(2)

___TRACE:___   Thu Sep 17 08:33:55 2015.852
SIP Signalling(2231): ./src/SIPService.cpp:740 Engine->RegService: sipRegisterSuccess

___TRACE:___   Thu Sep 17 08:33:55 2015.852
SIP Signalling(2231): ./src/sg_reg_service.cpp:742 sgEvtRegisterSuccess() - LineId(1/0) RegState(4) InformCC(0) Terminating(0)

___TRACE:___   Thu Sep 17 08:33:55 2015.852
SIP Signalling(2231): ./src/sgRecordObjReg.cpp:128 CurrentRegAction - LineId(1/0) transition from(2) to(0)

___TRACE:___   Thu Sep 17 08:33:55 2015.854
SIP Signalling(3211): ./src/sg_engine.cpp:2731 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(REGISTER)

___TRACE:___   Thu Sep 17 08:33:55 2015.852
SIP Signalling(2231): ./src/sg_reg_service.cpp:923 sgEvtRegisterSuccess() - LineId(1/0) RegState(3) InformCC(0) AddressFamilyChanged(0)

 

[KunterBunter]

Trage mal im Bild statt "fritz.box" die in deinem Fall richtige IP-Adresse 192.168.160.254 der Fritzbox ein.

 

[mini01]

Der Trace zeigt einen Anruf, der (glaubi) zum AB umgeleitet wird und die Aufnahme einige Sekunden dauert. Von MWI ist leider nichts zu sehen, d.h. die MWI-Feature scheint nicht korrekt am Laufen zu sein ...

Was wir analysieren wollen:
- MWI Anzeige zw. Phone und fritz.box (FB)
An was man das sieht:
- Am MWI-SUBSCRIBE vom Phone zur FB
Wo man das sieht:
- Im internen Phone Trace (SIP Signalling u SIP Messages auf DEBUG - alles andere auf NONE) bzw. in einem Wireshark-Trace beim Initialisieren von der Phone-internen SIP-Komponente
Wie kann man die SIP-Komponente neu initialisieren:
-Entweder durch normalen Phone-Restart, oder durch eine Änderung von bestimmten SIP-Einstellungen (bspw. unter Admin > System > SIP interface > Call transaction timer)

 

[US31994]

Danke für die Hilfe aber ich lasse das ganze erstmal ruhen, weil ich es nicht hin bekommen.
Habe auf der Fritzbox die Mailoption jetzt eingeschaltet das ist dann als Ersatz gedacht.
Vielleicht liegt es ja an der Software der Fritzbox da ist eine Beta drauf
Danke

 

[US31994]

Hallo

Danke nochmals für die HILFE
Habe heute die Beta aus der Fritzbox entfernt und siehe da die Briefkastenlampe geht an

Bis dann