Anfängerprobleme

Konfigurationsdiskussionen bei Asterisk direkt

Moderatoren: rainer, Tim

orpheus
Anfänger
Beiträge: 6
Registriert: Di 4. Mär 2014, 16:58

Anfängerprobleme

Beitrag von orpheus »

Hallo,

ich mache gerade die ersten Versuche mit asterisk. Bei mir läuft FreePBX auf einem raspberrypi. das ganze läuft momentan rein lokal ohne inet anbindung.
Als SIP Apparate dienen ein OpenStage40 und ein OpenStage60.
Ich bin soweit gekommen dass beide Apparate angelegt und angemeldet sind. Alle Fehlermeldungen auf den Apparaten sind verschwunden und in der Admin GUI sind 2 Apparate online. Wenn ich den Hörer abhebe höre ich das Freizeichen.
Die beiden Apparate haben die Nummer 1001 und 1002 und mit diesen habe ich auch die Extensions angelegt.
Wenn ich versuche von einem Apparat den anderen anzurufen passiert scheinbar nichts ,aber die CPU am raspberry geht auf 100%. Nach dem Auflegen ist sie wieder auf 0%

Ich habe versucht in diversen Logfiles was mi zu tracen bin aber nicht schlauer geworden.

Kann mir jemand helfen?

LG Stefan
Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Anfängerprobleme

Beitrag von rainer »

Hallo Stefan,

kannst du dich auf dem raspberrypi via ssh einloggen?

Wenn ja,

Code: Alles auswählen

asterisk -vvvvvvvvvvvvvvr 
aufrufen.
Dann wieder von der 1001 auf die 1002 anrufen und den output von asterisk -vvvvvvvvvvvvvvr hier posten.

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10
Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Anfängerprobleme

Beitrag von rainer »

Hi Stefan,

CPU 100% kann ein transcoding Problem sein.
z.B. wenn dein App. 1001 nur den Codec G722 kann und der App. 1002 nur G711a bzw. alaw ...
Dann übernimmt die CPU bzw. dein asterisk das transcoding der beiden Codece
App.1001 sendet G722 an den asterisk - CPU rechnet G722 in G711 um - asterisk sendet G711 an den App. 1002

Transcoding ist immer mit einer hohen CPU Last verbunden.
Die Codece kannst du in dem SIP Apparater einstellen.
z.B. G.711 bzw. alaw bzw. pcma bzw. G711a ... der hat leider mehrere Namen (as used in Europe)

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10
orpheus
Anfänger
Beiträge: 6
Registriert: Di 4. Mär 2014, 16:58

Re: Anfängerprobleme

Beitrag von orpheus »

Hallo Rainer,

leider lässt sich asterisk so nicht starten
Unable to connect to remote asterisk (does /var/run/asterisk/asterisk.ctl exist?)

Ohne dem Anhang nach dem - startet asterisk.

Als Codec habe ich nur alaw aufgedreht und auf den Phones alles außer G711 deaktiviert.
Bild
Bild

LG Stefan
Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Anfängerprobleme

Beitrag von rainer »

Hallo Stefan,

kannst du dich via ssh als root auf deinem raspberrypi anmelden?
oder ein

Code: Alles auswählen

sudo asterisk -vvvvvvvvvvvvvvvvvvvvr
starten?

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10
orpheus
Anfänger
Beiträge: 6
Registriert: Di 4. Mär 2014, 16:58

Re: Anfängerprobleme

Beitrag von orpheus »

Hallo Rainer,

ja bin als root angemeldet.
Habs jetzt auch mit G722 probiert - selbes Verhalten.

LG Stefan
Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Anfängerprobleme

Beitrag von rainer »

Hi Stefan,

das mit dem transcoding ist auch nur eine Vermutung.

Schau mal ob du im Asterisk etwas mit directmedia findest und schalte es ein.

directmedia=yes

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10
orpheus
Anfänger
Beiträge: 6
Registriert: Di 4. Mär 2014, 16:58

Re: Anfängerprobleme

Beitrag von orpheus »

OK mach ich.
Noch was: Da ich im ersten Step rein intern telefonieren will hab ich keinen Trunk oder dial plan definiert. Wäre das auch für intern notwendig?

LG Stefan
orpheus
Anfänger
Beiträge: 6
Registriert: Di 4. Mär 2014, 16:58

Re: Anfängerprobleme

Beitrag von orpheus »

Ich hab vom Telefon aus einen Trace gemacht. Ist zwar ein langer Wulst aber vielleicht kannst du was raus lesen.

___TRACE:___ Fri Nov 16 23:10:08 2012.443
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet In <---10.0.0.111
OPTIONS sip:1001@10.0.0.3:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.111:5060;branch=z9hG4bK05db9f9d
Max-Forwards: 70
From: "Unknown" <sip:Unknown@10.0.0.111>;tag=as461f16de
To: <sip:1001@10.0.0.3:5060;transport=udp>
Contact: <sip:Unknown@10.0.0.111:5060>
Call-ID: 6a4fb2ec5d48fd6a333e56a4094d02bd@10.0.0.111:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.6.0)
Date: Thu, 01 Jan 1970 02:10:38 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sg_engine.cpp:4750 EvOnPacketReceived(OPTIONS)

___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sg_engine.cpp:4772 packet does not match an existing dialog

___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sgCEngineHelper.cpp:88 HasContentTypeHeader() - error retrieving the ContentType header

___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sgCEngineHelper.cpp:88 HasContentTypeHeader() - error retrieving the ContentType header

___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sg_engine.cpp:4788 EvOnPacketReceived IsMWI(0) IsCheckSync(0) IsServerStatusHeader(0) IsUACSTARequestContent(0) IsDLSContactMeRequestContent(0)

___TRACE:___ Fri Nov 16 23:10:08 2012.444
SIP Signalling(834): ./src/sg_engine.cpp:4949 EvOnPacketReceived() Send that request to the bad request

___TRACE:___ Fri Nov 16 23:10:08 2012.445
SIP Signalling(834): ./src/sg_engine_events.cpp:1180 EvRequest() OPTIONS received on

___TRACE:___ Fri Nov 16 23:10:08 2012.445
SIP Signalling(834): ./src/sg_engine_events.cpp:1187 EvRequest() - OPTIONS received on m_BadRequestSipContext : request will be rejected

___TRACE:___ Fri Nov 16 23:10:08 2012.446
SIP Signalling(834): ./src/sg_engine_events.cpp:1212 EvRequest() -> eSIP_METHOD_OPTIONS

***ERROR:*** Fri Nov 16 23:10:08 2012.447
SIP Signalling(834): ./src/sgCEngineHelper.cpp:299 RemoveSupported() - res(-2147483647,FE:Fail)

___TRACE:___ Fri Nov 16 23:10:08 2012.447
SIP Signalling(835): ./src/sg_engine.cpp:9786 EvPacketReceived(ISipTransportObserver) - REQ Method(OPTIONS)

___TRACE:___ Fri Nov 16 23:10:08 2012.450
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.111:5060;branch=z9hG4bK05db9f9d
From: "Unknown" <sip:Unknown@10.0.0.111>;tag=as461f16de
To: <sip:1001@10.0.0.3:5060;transport=udp>;tag=237370329
Call-ID: 6a4fb2ec5d48fd6a333e56a4094d02bd@10.0.0.111:5060
CSeq: 102 OPTIONS
Server: OpenStage_40_V3 R1.38.0 SIP 121115
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:652 Failed to find callref for calltype: ccPreConsultationCall : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:645 CALLREC ALLOC = 103 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:652 Failed to find callref for calltype: ccPrimaryCall : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:652 Failed to find callref for calltype: ccConsultationCall : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:680 Set calltype = ccPrimaryCall for callref 103 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.865
SIP Call Control(845): ./src/cc_tracing.c:470 Line 0: E_FM_new_outgoing_call: S_FM_idle ---> S_FM_new_call_outgoing : : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 1 calltype 0 current_state 0 callref 103
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 2 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 3 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 4 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 5 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_events.c:807 Exit action not defined for state : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.866
SIP Call Control(845): ./src/cc_tracing.c:470 Line 0: E_CC_call_make: S_CC_idle ---> S_CC_call_setup : 103 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 103, newState = ccCallSettingUp, traceId = '')

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_notify.c:62 # calls(incremented): 1

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.874
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 5; state = 2

___TRACE:___ Fri Nov 16 23:10:13 2012.874
SIP Signalling(507): ./src/sgCDispatcher.cpp:606 CC->SIG: sgCallMake:

___TRACE:___ Fri Nov 16 23:10:13 2012.874
SIP Signalling(507): ./src/sg_init_service.cpp:109 SetStackTime

---INFO:--- Fri Nov 16 23:10:13 2012.875
SIP Signalling(507): ./src/sg_init_service.cpp:121 GMT: 2012 Nov 16 23:10:13 Fri

---INFO:--- Fri Nov 16 23:10:13 2012.875
SIP Signalling(507): ./src/sg_init_service.cpp:129 UTC: y(112) m(10) d(16) h(23) m(10) s(13)

___TRACE:___ Fri Nov 16 23:10:13 2012.875
SIP Signalling(507): ./src/sg_call_service_int.cpp:135 sgCallSetup() BRIDGE DATA: CallId() CallToUri() CallTo() CallFrom() Enabled(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.875
SIP Signalling(507): ./src/sgCallRecordMgmt.cpp:76 CreateCallRecord() - new CallRecord(103,0) created, 1 CallRecord(s) in total

___TRACE:___ Fri Nov 16 23:10:13 2012.867
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.875
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.875
SIP Call Control(845): ./src/cc_notify.c:823 Call ref=103: Old state=ccNull: New state=ccCallSettingUp:

___TRACE:___ Fri Nov 16 23:10:13 2012.876
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.876
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.876
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_tracing.c:652 Failed to find callref for calltype: ccWaitingCall : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_tracing.c:470 Line 0: E_FM_wait: S_FM_new_call_outgoing ---> S_FM_primary_call_wait_state : : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 1 calltype 0 current_state 1 callref 103
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 2 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 3 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 4 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.877
SIP Call Control(845): ./src/cc_features.c:2725 Feature state change call leg 5 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:13 2012.878
SIP Call Control(845): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.885
SIP Signalling(834): ./src/sg_engine.cpp:4317 EvMessageServiceMgrAwaken(1, 5, 0xa47c00)

___TRACE:___ Fri Nov 16 23:10:13 2012.895
SIP Signalling(507): ./src/sg_sip_url_service.cpp:53 sgSetSipURL() - pInRemURL(1002) unLineId(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.895
SIP Signalling(507): ./src/sg_sip_url_service.cpp:217 sgSetSipURL() - URI<sip:(1002)@(10.0.0.111):(5060)>

___TRACE:___ Fri Nov 16 23:10:13 2012.896
SIP Signalling(507): ./src/sgCDatabaseIf.cpp:748 GetVideoCapability() - eVideoCapability(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.896
SIP Signalling(507): ./src/MediaSessionMgr.cpp:742 KW: eRtpMediaType(0) bHasRtp(0) bHasSrtpMikey(0) bHasSrtpSdes(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.909
SIP Signalling(507): ./src/MediaSessionMgr.cpp:168 sgCreateSession() - getLocalSessionDescription: <media-sessiondescription><ip-addr>10.0.0.3</ip-addr><port>5010</port><local-socket><ip-addr-v4>10.0.0.3</ip-addr-v4><port>5010</port><rtcp-port>5011</rtcp-port></local-socket><dtmf-payload>101</dtmf-payload><ptime>0</ptime><max-ptime>60</max-ptime><audio><codec>G722</codec></audio><ssrc>3668879874</ssrc></media-sessiondescription>

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/MediaSessionMgr.cpp:186 sgCreateSession() - pRtpSession(a2d2a0) SessionHandle(2) eMediaType(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/MediaSessionMgr.cpp:768 GenerateRtpSessionsHelper() - New RtpSession created, pRtpSession(0xa2d2a0)

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/MediaSessionMgr.cpp:817 GenerateRtpSessionsHelper() - created a vector(0xa45f90) with 1 RtpSession(s) eRtpMediaType(0) total(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/MediaSessionMgr.cpp:706 GenerateRtpSessions() - created a vector(0xa45f90) with 1 RtpSession(s)

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/sgCallRecordNew.cpp:224 SetRtpSessions() - replacing m_pvRtpSessions((nil)) with pRtpSession(0xa45f90)

___TRACE:___ Fri Nov 16 23:10:13 2012.911
SIP Signalling(507): ./src/sgSdpService.cpp:577 SdpStateEventMachine() - CallRefId(103) CalLRefIdForCC(103) SdpState(0,SDP_NULL) eTypeOfMessage(0) bIncludeSdp(1) bResetCaps(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.912
SIP Signalling(507): ./src/sgSdpService.cpp:1807 GenerateLocalCapsMgr() - CallRefId(103) CalLRefIdForCC(103) bSdpOffer(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.912
SIP Signalling(507): ./src/sgSdpService.cpp:1833 GenerateLocalCapsMgr() - bAnatEnabled(0) bSDESenabled(0) bDualstack(1) bPayloadSecurityEnabled(0) eMediaIPMode(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.912
SIP Signalling(507): ./src/sgSdpService.cpp:1842 GenerateLocalCapsMgr() - based on 1 RTPSession(s)

___TRACE:___ Fri Nov 16 23:10:13 2012.912
SIP Signalling(507): ./src/sgSdpService.cpp:1883 GenerateLocalCapsMgr() - uNumSockets(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.912
SIP Signalling(507): ./src/sgSdpService.cpp:1893 GenerateLocalCapsMgr() - IPV4 socket found

___TRACE:___ Fri Nov 16 23:10:13 2012.913
SIP Signalling(507): ./src/sgSdpService.cpp:1929 GenerateLocalCapsMgr() - m-line(0) set to RtpMediaType(0) eRTPAVP

___TRACE:___ Fri Nov 16 23:10:13 2012.913
SIP Signalling(507): ./src/sgSdpService.cpp:1981 GenerateLocalCapsMgr() - m-line(0): codec(G722) added at index(0), silenceSuppression(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:2072 GenerateLocalCapsMgr() - remove m-line(0) local connections data

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:2160 GenerateLocalCapsMgr() - GetCapsLocal((nil)) GetCapsRemote((nil)) GetCapsNegotiated((nil)) GetCapsNegotiatedLast((nil)) HasRemoteSdpOriginChanged(0) bSdpOffer(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:2255 GenerateLocalCapsMgr() - setting streams to bInactive(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:2285 GenerateLocalCapsMgr() - CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL) bInactive(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:640 SdpStateEventMachine() - INVITE_REQ_OUTGOING: LOCAL

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgCallRecordNew.cpp:339 SetCapsToUseForSdp() - using eCAPS_MGR_LOCAL for SDP

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgCallRecordNew.cpp:401 SdpState - Call(103) transition from (0,SDP_NULL) to (1,SDP_OFFER_SENT)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgCallRecordNew.cpp:507 CallPhase - Call(103) transition from (0,eCALL_P_NULL) to (1,eCALL_P_INITIAL)

___TRACE:___ Fri Nov 16 23:10:13 2012.914
SIP Signalling(507): ./src/sgSdpService.cpp:1429 SdpStateEventMachine() END - rCallRecord(103) rCallRecord.GetSdpStateStr(1,SDP_OFFER_SENT) eCallEvent(0) bIncludeSdp(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.915
SIP Signalling(507): ./src/sgSdpService.cpp:192 GetSdp() - CallRefId(103) CallRefIdForCC(103) bIncludeSdp(1)

___TRACE:___ Fri Nov 16 23:10:13 2012.915
SIP Signalling(507): ./src/sgSdpService.cpp:219 GetSdp() - generating SDP based on LOCAL_CAPS

___TRACE:___ Fri Nov 16 23:10:13 2012.915
SIP Signalling(507): ./src/sgSdpService.cpp:410 GetSdp() - szSessionAddr(10.0.0.3) strLocalAddr.CStr(10.0.0.3)

___TRACE:___ Fri Nov 16 23:10:13 2012.916
SIP Signalling(507): ./src/sgSdpService.cpp:452 GetSdp() - sdpPacket.IsValid(1) GetSessionTimeEnable(0) GetServerType(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.917
SIP Signalling(507): ./src/sg_call_service_int.cpp:375 sgCallSetup()/InviteA - CallRefId(103) CallRefIdForCC(103)

___TRACE:___ Fri Nov 16 23:10:13 2012.925
SIP Signalling(834): ./src/sg_engine.cpp:4317 EvMessageServiceMgrAwaken(0, 14, 0xa47c00)

___TRACE:___ Fri Nov 16 23:10:13 2012.925
SIP Signalling(834): ./src/sgCallInfoMgmt.cpp:58 CreateCallInfo() - new CallInfo(103) created, 1 CallInfo(s) in total

___TRACE:___ Fri Nov 16 23:10:13 2012.925
SIP Signalling(834): ./src/sg_engine.cpp:1665 InternalInviteA() - rRemoteUri: user(1002) host(10.0.0.111) port(5060)

___TRACE:___ Fri Nov 16 23:10:13 2012.925
SIP Signalling(834): ./src/sg_engine.cpp:12050 CreateSipContext() - CallInfo created, lineId(0), callRefId(103)

___TRACE:___ Fri Nov 16 23:10:13 2012.927
SIP Signalling(834): ./src/sg_engine.cpp:10517 AddUserAgentSvc() - IPv4(10.0.0.3)(1) IPv6(NULL)(0)

___TRACE:___ Fri Nov 16 23:10:13 2012.927
SIP Signalling(834): ./src/sg_engine.cpp:12099 CreateSipContext() - bTlsUsed(0) GetRouteToUse(0xa46868) m_pPrimaryRoute(0xa46868) m_pBackupRoute(0xa46920)

___TRACE:___ Fri Nov 16 23:10:13 2012.927
SIP Signalling(834): ./src/sg_engine_auth.cpp:40 AddDigestClientAuthSvc() SetLoopThreshold(3)

___TRACE:___ Fri Nov 16 23:10:13 2012.930
SIP Signalling(834): ./src/sg_engine_rel.cpp:26 AddReliableProvisionalResponseSvc()

~~~DEBUG:~~~ Fri Nov 16 23:10:13 2012.938
SIP Signalling(834): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___ Fri Nov 16 23:10:13 2012.939
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 143; state = 2

___TRACE:___ Fri Nov 16 23:10:13 2012.939
SIP Signalling(507): ./src/sgCDispatcher.cpp:338 Engine->CallService: sipCallProceeding

___TRACE:___ Fri Nov 16 23:10:13 2012.939
SIP Signalling(507): ./src/sg_call_service_ext.cpp:5067 SIG->CC: sgEvtCallProceeding()/sgCallStateProceeding - CallRefId(103)

~~~DEBUG:~~~ Fri Nov 16 23:10:13 2012.939
SIP Signalling(507): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 12

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef: : 103

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_events.c:807 Exit action not defined for state : 1

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_tracing.c:470 Line 0: E_CC_call_proceeding: S_CC_call_setup ---> S_CC_call_proceeding : 103 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 103, newState = ccCallProceeding, traceId = '')

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_notify.c:823 Call ref=103: Old state=ccCallSettingUp: New state=ccCallProceeding:

___TRACE:___ Fri Nov 16 23:10:13 2012.940
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.941
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.941
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:13 2012.986
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
INVITE sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0f55c76e01ee4914f
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>
Call-ID: 633783e47032989f
CSeq: 207551896 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Contact: "Test01" <sip:1001@10.0.0.3:5060;transport=udp>
Supported: replaces, 100rel
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 213

v=0
o=OpenStage-Line_0 92806189 1387304917 IN IP4 10.0.0.3
s=SIP Call
c=IN IP4 10.0.0.3
t=0 0
m=audio 5010 RTP/AVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv


___TRACE:___ Fri Nov 16 23:10:14 2012.003
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet In <---10.0.0.111
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0f55c76e01ee4914f;received=10.0.0.3
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>;tag=as3511df5f
Call-ID: 633783e47032989f
CSeq: 207551896 INVITE
Server: FPBX-2.11.0(11.6.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6e66dbd5"
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:14 2012.006
SIP Signalling(834): ./src/sg_engine_auth.cpp:72 EvCredentialsRequired()

___TRACE:___ Fri Nov 16 23:10:14 2012.006
SIP Signalling(834): ./src/sg_engine_auth.cpp:127 EvCredentialsRequired() - LineId(0) CallRefId(103)

___TRACE:___ Fri Nov 16 23:10:14 2012.013
SIP Signalling(834): ./src/sg_engine_auth.cpp:235 EvCredentialsRequired() - request reissued

___TRACE:___ Fri Nov 16 23:10:14 2012.015
SIP Signalling(835): ./src/sg_engine.cpp:9799 EvPacketReceived(ISipTransportObserver) - RES Code(401) Method(INVITE)

___TRACE:___ Fri Nov 16 23:10:14 2012.018
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
ACK sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0f55c76e01ee4914f
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>;tag=as3511df5f
Call-ID: 633783e47032989f
CSeq: 207551896 ACK
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:14 2012.024
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
INVITE sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>
Call-ID: 633783e47032989f
CSeq: 207551897 INVITE
Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
Allow-Events: hold
Authorization: Digest username="1001",realm="asterisk",nonce="6e66dbd5",uri="sip:1002@10.0.0.111:5060;transport=udp",response="373566148faa7991db8dad9d72c72726",algorithm=MD5
Contact: "Test01" <sip:1001@10.0.0.3:5060;transport=udp>
Supported: replaces, 100rel
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
X-Siemens-Call-Type: ST-insecure
Content-Type: application/sdp
Content-Length: 213

v=0
o=OpenStage-Line_0 92806189 1387304917 IN IP4 10.0.0.3
s=SIP Call
c=IN IP4 10.0.0.3
t=0 0
m=audio 5010 RTP/AVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv


___TRACE:___ Fri Nov 16 23:10:14 2012.038
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet In <---10.0.0.111
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581;received=10.0.0.3
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>
Call-ID: 633783e47032989f
CSeq: 207551897 INVITE
Server: FPBX-2.11.0(11.6.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:1002@10.0.0.111:5060>
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:14 2012.040
SIP Signalling(834): ./src/sg_engine.cpp:5940 EvProgress(ISipSessionSvc)

~~~DEBUG:~~~ Fri Nov 16 23:10:14 2012.040
SIP Signalling(834): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___ Fri Nov 16 23:10:14 2012.041
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 121; state = 2

___TRACE:___ Fri Nov 16 23:10:14 2012.041
SIP Signalling(507): ./src/sgCDispatcher.cpp:197 Engine->CallService: sipCallProgress

___TRACE:___ Fri Nov 16 23:10:14 2012.041
SIP Signalling(507): ./src/sg_call_service_ext.cpp:1916 sgEvtCallProgress() - START: CallRefId(103) CallState(0,eCALL_NULL) CallSubState(0,eCALL_SS_NULL)

___TRACE:___ Fri Nov 16 23:10:14 2012.042
SIP Signalling(507): ./src/sg_call_service_ext.cpp:1973 sgEvtCallProgress() - END 100Trying (100)

___TRACE:___ Fri Nov 16 23:10:14 2012.043
SIP Signalling(835): ./src/sg_engine.cpp:9799 EvPacketReceived(ISipTransportObserver) - RES Code(100) Method(INVITE)

___TRACE:___ Fri Nov 16 23:10:18 2012.409
SIP Call Control(327): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.409
SIP Call Control(327): ./src/cc_tracing.c:470 Line 0: E_FM_call_clear_local: S_FM_primary_call_wait_state ---> S_FM_local_clear : : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.410
SIP Call Control(327): ./src/cc_features.c:2725 Feature state change call leg 1 calltype 0 current_state 2 callref 103
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.410
SIP Call Control(327): ./src/cc_features.c:2725 Feature state change call leg 2 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.411
SIP Call Control(327): ./src/cc_features.c:2725 Feature state change call leg 3 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.411
SIP Call Control(327): ./src/cc_features.c:2725 Feature state change call leg 4 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.412
SIP Call Control(327): ./src/cc_features.c:2725 Feature state change call leg 5 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.412
SIP Call Control(327): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.413
SIP Call Control(327): ./src/cc_features.c:8949 ./src/cc_features.c:8948 ACT_local_clear() - Line=0xa30518, callref=103 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.413
SIP Call Control(327): ./src/cc_tracing.c:470 Line 0: E_FML_FORCE: : S_FM_local_clear ---> S_FM_primary_call_wait_state : : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.414
SIP Call Control(327): ./src/cc_events.c:807 Exit action not defined for state : 2

___TRACE:___ Fri Nov 16 23:10:18 2012.414
SIP Call Control(327): ./src/cc_tracing.c:470 Line 0: E_CC_call_clear_local: S_CC_call_proceeding ---> S_CC_call_clearing : 103 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.415
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 10; state = 2

___TRACE:___ Fri Nov 16 23:10:18 2012.415
SIP Signalling(507): ./src/sgCDispatcher.cpp:626 CC->SIG: sgCallDisconnect:

___TRACE:___ Fri Nov 16 23:10:18 2012.415
SIP Signalling(507): ./src/sg_call_service_int.cpp:402 sgLocalCallClear() - callRefId(103) lineId(0)

***ERROR:*** Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/MediaSessionMgr.cpp:917 GetRtpStats() - Invalid RtpSession((nil))

___TRACE:___ Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/MediaSessionMgr.cpp:492 sgSetSessionTone() - eToneRequest(4) ignored

___TRACE:___ Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/sg_call_service_int.cpp:470 sgLocalCallClear()/RejectA - CallRefId(103) lineID(0) reason(0)

___TRACE:___ Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/sg_engine.cpp:2672 RejectA()

___TRACE:___ Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/sg_call_service_int.cpp:510 sgLocalCallClear()/sgCallStateDisconnected - CallRefId(103) CallRefIdForCC(103) clearingCause(0)

~~~DEBUG:~~~ Fri Nov 16 23:10:18 2012.416
SIP Signalling(507): ./src/SIP_Service.cpp:185 createSIPService: call HandleSignallingEvent

___TRACE:___ Fri Nov 16 23:10:18 2012.419
SIP Signalling(834): ./src/sg_engine.cpp:4317 EvMessageServiceMgrAwaken(0, 16, 0xa59140)

___TRACE:___ Fri Nov 16 23:10:18 2012.419
SIP Signalling(834): ./src/sg_engine.cpp:2690 InternalRejectA()

___TRACE:___ Fri Nov 16 23:10:18 2012.419
SIP Signalling(834): ./src/sg_engine.cpp:2727 InternalRejectA() - rejecting pActiveDialog(0xa282f0) at CallInfo CallRefId(103)

___TRACE:___ Fri Nov 16 23:10:18 2012.419
SIP Signalling(834): ./src/sg_engine.cpp:2766 InternalRejectA_Helper() - pDialog(0xa282f0) SipContext(0xa28cb0) CallRefId(103)

___TRACE:___ Fri Nov 16 23:10:18 2012.425
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
CANCEL sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>
Call-ID: 633783e47032989f
CSeq: 207551897 CANCEL
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
Warning: 399 10.0.0.3 "Call is terminated"
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:18 2012.426
SIP Call Control(327): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 103, newState = ccCallClearing, traceId = '')

___TRACE:___ Fri Nov 16 23:10:18 2012.426
SIP Call Control(327): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.427
SIP Call Control(327): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.436
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet In <---10.0.0.111
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581;received=10.0.0.3
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>;tag=as58bab7f6
Call-ID: 633783e47032989f
CSeq: 207551897 INVITE
Server: FPBX-2.11.0(11.6.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:18 2012.438
SIP Signalling(834): ./src/sg_engine.cpp:5396 EvFailure() - CallRefId(103) bHidden(0) DialogSize(1)

~~~DEBUG:~~~ Fri Nov 16 23:10:18 2012.438
SIP Signalling(834): ./src/SIP_Service.cpp:194 SIP_Service::dispatchMsgToSignalling: entering

___TRACE:___ Fri Nov 16 23:10:18 2012.439
SIP Signalling(835): ./src/sg_engine.cpp:9799 EvPacketReceived(ISipTransportObserver) - RES Code(487) Method(INVITE)

___TRACE:___ Fri Nov 16 23:10:18 2012.442
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet In <---10.0.0.111
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581;received=10.0.0.3
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>;tag=as58bab7f6
Call-ID: 633783e47032989f
CSeq: 207551897 CANCEL
Server: FPBX-2.11.0(11.6.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:18 2012.442
SIP Signalling(835): ./src/sg_engine.cpp:9799 EvPacketReceived(ISipTransportObserver) - RES Code(200) Method(CANCEL)

___TRACE:___ Fri Nov 16 23:10:18 2012.444
SIP Messages(835): ./src/sgCSipPacketLog.cpp:101 :

Packet Out--->10.0.0.111
ACK sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>;tag=as58bab7f6
Call-ID: 633783e47032989f
CSeq: 207551897 ACK
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
Content-Length: 0



___TRACE:___ Fri Nov 16 23:10:18 2012.449
SIP Call Control(327): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.449
SIP Call Control(327): ./src/cc_notify.c:823 Call ref=103: Old state=ccCallProceeding: New state=ccCallClearing:

___TRACE:___ Fri Nov 16 23:10:18 2012.450
SIP Call Control(327): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.451
SIP Call Control(327): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.451
SIP Call Control(327): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

***ERROR:*** Fri Nov 16 23:10:18 2012.452
SIP Call Control(327): ./src/cc_tracing.c:467 Line 0: E_CC_call_clear_local: S_CC_call_clearing ---> OUT OF CONTEXT EVENT : 103

___TRACE:___ Fri Nov 16 23:10:18 2012.453
SIP Call Control(327): ./src/cc_tracing.c:470 Line 0: E_CC_call_clear_local: S_CC_call_clearing ---> OUT OF CONTEXT EVENT : 103 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.454
SIP Call Control(507): ./src/cc_sig_events.c:52 HandleSignallingEvent - received event. Event: : 17

___TRACE:___ Fri Nov 16 23:10:18 2012.454
SIP Call Control(507): ./src/cc_sig_events.c:34 NotifyStateMachine - CallRef: : 103

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_events.c:807 Exit action not defined for state : 9

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_tracing.c:470 Line 0: E_CC_call_clear_remote: S_CC_call_clearing ---> S_CC_call_cleared : 103 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_notify.c:795 **** ccCallStateChange - reached start **** (thisCall = 103, newState = ccCallCleared, traceId = '')

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_notify.c:823 Call ref=103: Old state=ccCallClearing: New state=ccCallCleared:

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.455
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.456
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.456
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_notify.c:90 Number of calls (decremented): 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_events.c:1936 ACT_call_cleared - : 103

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_tracing.c:470 Line 0: E_FM_call_cleared: S_FM_primary_call_wait_state ---> S_FM_primary_call_cleared : : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 1 calltype 0 current_state 11 callref 103
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 2 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 3 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 4 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.457
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 5 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_tracing.c:645 CALLREC FREE = 103 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_tracing.c:652 Failed to find callref for calltype: ccIsolatedCall : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:10563 lockSIPLine(0xa59730, -1) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_tracing.c:470 Line 0: E_FM_wait: S_FM_primary_call_cleared ---> S_FM_idle : : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 1 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 2 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 3 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.458
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 4 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.459
SIP Call Control(507): ./src/cc_features.c:2725 Feature state change call leg 5 calltype 14 current_state 0 callref 0
: 0

___TRACE:___ Fri Nov 16 23:10:18 2012.459
SIP Call Control(507): ./src/cc_features.c:10586 unlockSIPLine(0xa59730) - retval 1 : 0

___TRACE:___ Fri Nov 16 23:10:18 2012.459
SIP Signalling(507): ./src/sg_engine.cpp:12247 RemoveCallA() - unCallRefId(103)

___TRACE:___ Fri Nov 16 23:10:18 2012.459
SIP Signalling(507): ./src/sgCallRecordNew.cpp:1322 StopRetryTimer() - stopping RetryTimer((nil)) at call(103)

___TRACE:___ Fri Nov 16 23:10:18 2012.459
SIP Signalling(507): ./src/MediaSessionMgr.cpp:207 sgCloseSession() - closing pRtpSession(0xa2d2a0) with handle(2)

___TRACE:___ Fri Nov 16 23:10:18 2012.460
SIP Signalling(834): ./src/sg_engine.cpp:4317 EvMessageServiceMgrAwaken(0, 21, 0xa59140)

___TRACE:___ Fri Nov 16 23:10:18 2012.461
SIP Signalling(834): ./src/sg_engine.cpp:12268 InternalRemoveCallA() - unCallRefId(103) bCalledFromExt(1)

___TRACE:___ Fri Nov 16 23:10:18 2012.461
SIP Signalling(834): ./src/sg_engine.cpp:4590 CSipEngine(0xa447a8)::StartTimer(103, 0xa47c00)

___TRACE:___ Fri Nov 16 23:10:18 2012.461
SIP Signalling(834): ./src/sg_engine.cpp:4632 CSipEngine(0xa447a8)::StartTimerExit(0)

___TRACE:___ Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sgCallRecordMgmt.cpp:94 RemoveCallRecord() - CallRecord(103,103) removed, 0 CallRecord(s) left

___TRACE:___ Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 123; state = 2

___TRACE:___ Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sgCDispatcher.cpp:209 Engine->CallService: sipCallFailed

***ERROR:*** Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sgCallRecordMgmt.cpp:131 GetCallRecord() - CallRecord with CallRefId(103) not found

***ERROR:*** Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sg_call_service_ext.cpp:2874 sgEvtCallFailed() - no CallRecord for callRefId(103) available

___TRACE:___ Fri Nov 16 23:10:18 2012.464
SIP Signalling(507): ./src/sg_engine.cpp:2672 RejectA()

___TRACE:___ Fri Nov 16 23:10:18 2012.465
SIP Signalling(507): ./src/sgCDispatcher.cpp:128 sgCDispatcher::sgParseIncomingMessage - event = 18; state = 2

___TRACE:___ Fri Nov 16 23:10:18 2012.465
SIP Signalling(507): ./src/sgCDispatcher.cpp:631 CC->SIG: sgCallDisconnectedConfirm:

***ERROR:*** Fri Nov 16 23:10:18 2012.465
SIP Signalling(507): ./src/sgCallRecordMgmt.cpp:131 GetCallRecord() - CallRecord with CallRefId(103) not found

***ERROR:*** Fri Nov 16 23:10:18 2012.465
SIP Signalling(507): ./src/sg_call_service_ext.cpp:78 sgFarCallClearConfirm() - no CallRecord for callRefId(103) available

___TRACE:___ Fri Nov 16 23:10:18 2012.465
SIP Signalling(834): ./src/sg_engine.cpp:4317 EvMessageServiceMgrAwaken(0, 16, 0xa59140)

___TRACE:___ Fri Nov 16 23:10:18 2012.465
SIP Signalling(834): ./src/sg_engine.cpp:2690 InternalRejectA()

___TRACE:___ Fri Nov 16 23:10:18 2012.466
SIP Signalling(834): ./src/sg_engine.cpp:2727 InternalRejectA() - rejecting pActiveDialog(0xa282f0) at CallInfo CallRefId(103)

___TRACE:___ Fri Nov 16 23:10:18 2012.466
SIP Signalling(834): ./src/sg_engine.cpp:2766 InternalRejectA_Helper() - pDialog(0xa282f0) SipContext(0xa28cb0) CallRefId(103)

***ERROR:*** Fri Nov 16 23:10:18 2012.466
SIP Signalling(834): ./src/sg_engine.cpp:2843 InternalRejectA_Helper() - no ISipServerEventControl found for CallRefId(103) LineID(0)

***ERROR:*** Fri Nov 16 23:10:18 2012.466
SIP Signalling(834): ./src/sg_engine.cpp:2951 InternalRejectA_Helper() - BYE ERROR, rres(80000002)(FE:Invalid state)

***ERROR:*** Fri Nov 16 23:10:18 2012.466
SIP Signalling(834): ./src/sg_engine.cpp:2742 InternalRejectA() - InternalRejectA_Helper() did not succeed, result(-2147483646,FE:Invalid state) pActiveDialog(0xa282f0) CallRefId(103)

LG Stefan
Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Anfängerprobleme

Beitrag von rainer »

Hi Stefan,

vom App. 1002 kommt ein Warning: 399 10.0.0.3 "Call is terminated"
und das Datum und die Uhrzeit im openstage steht auf Nov 16 23:10:18 2012 (nur um das auszuschließen)


Packet Out--->10.0.0.111
CANCEL sip:1002@10.0.0.111:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.0.3;branch=z9hG4bK0fb99af1bf1333581
Route: <sip:10.0.0.111:5060;lr;transport=udp>
Max-Forwards: 70
From: "Test01" <sip:1001@10.0.0.111:5060>;tag=0fd0c1ba68;epid=SC448604
To: <sip:1002@10.0.0.111:5060>
Call-ID: 633783e47032989f
CSeq: 207551897 CANCEL
User-Agent: OpenStage_40_V3 R1.38.0 SIP 121115
Warning: 399 10.0.0.3 "Call is terminated"
Content-Length: 0
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10
Antworten