[cisco-voip] Jabber to Huawei MCU
José Paulo de Oliveira Petry
petrybr at gmail.com
Thu May 8 09:14:45 EDT 2014
Hello,
Im not a H323 expert, so if someone could help me finding and solving this
problem i'll really appreciate!
My costumer has IP Telephony from Cisco (CUCM, Jabber, Cisco phones...) and
Videoconference from Huawei.
As a plan to migrate their videoconference to Cisco the first step is
interoperability so they can migrate bit by bit
Im using a Cisco VCS Control to do the interworking SIP-H323, so the
topology is this:
CUCM <-SIP-> VCS <-H323-> Huawei Gatekeeper
When i call from Jabber to a Huawei endpoint, everythings works fine:
Jabber -> CUCM -> VCS -> Huawei GK -> Huawei Endpoint
But when im calling from Jabber to Huaweii MCU it doesnt works, the call is
answered but in less than a second it is finished
Jabber -> CUCM -> VCS -> Huawei GK -> Huawei MCU
I guess it is a codec mismatch, but as im not a H323 expert i cant confirm
what is really going on and if it is really a codec mismatch.
Attached is the log captured from VCS.
Thanks in advance for any help!
Regards,
José Paulo de Oliveira Petry
petrybr at gmail.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20140508/dc2d13ff/attachment.html>
-------------- next part --------------
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,326" Module="network.sip" Level="INFO": Src-ip="10.6.99.2" Src-port="53566" Detail="Receive Request Method=INVITE, Request-URI=sip:4610003 at 10.6.99.71:5060, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,326" Module="network.sip" Level="DEBUG": Src-ip="10.6.99.2" Src-port="53566"
SIPMSG:
|INVITE sip:4610003 at 10.6.99.71:5060 SIP/2.0
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 INVITE
Remote-Party-ID: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;party=calling;screen=yes;privacy=off
Contact: <sip:2972 at 10.6.99.2:5060;transport=tcp>;video;audio;video;bfcp
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>
Max-Forwards: 69
Allow: INVITE,OPTIONS,INFO,BYE,CANCEL,ACK,PRACK,UPDATE,REFER,SUBSCRIBE,NOTIFY
User-Agent: Cisco-CUCM8.6
Expires: 180
Date: Wed, 07 May 2014 18:12:15 GMT
Supported: timer,resource-priority,replaces,X-cisco-srtp-fallback,X-cisco-original-called
Session-Expires: 1800
Min-SE: 1800
P-Asserted-Identity: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>
Allow-Events: presence
Cisco-Guid: 0485584640-0000065536-0000001340-0040044042
Content-Length: 0
|
2014-05-07T15:05:50-03:00 vcsc tvcs: Event="Search Attempted" Service="SIP" Src-alias-type="SIP" Src-alias="2972 at 10.6.99.2" Dst-alias-type="SIP" Dst-alias="sip:4610003 at 10.6.99.71:5060" Call-serial-number="38e104be-d612-11e3-bf3a-0010f3230a42" Tag="38e10662-d612-11e3-8836-0010f3230a42" Detail="searchtype:INVITE" Level="1" UTCTime="2014-05-07 18:05:50,329"
2014-05-07T15:05:50-03:00 vcsc tvcs: Event="Call Attempted" Service="SIP" Src-ip="10.6.99.2" Src-port="5060" Src-alias-type="SIP" Src-alias="sip:2972 at 10.6.99.2" Dst-alias-type="SIP" Dst-alias="sip:4610003 at 10.6.99.71:5060" Call-serial-number="38e104be-d612-11e3-bf3a-0010f3230a42" Tag="38e10662-d612-11e3-8836-0010f3230a42" Protocol="TCP" Auth="NO" Level="1" UTCTime="2014-05-07 18:05:50,330"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,331" Module="network.cpl" Level="DEBUG": Remote-ip="10.6.99.2" Remote-port="5060" Detail="CPL: <routed> "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,331" Module="network.cpl" Level="DEBUG": Remote-ip="10.6.99.2" Remote-port="5060" Detail="CPL: <proxy stop-on-busy="no" timeout="0"/> "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,331" Module="network.sip" Level="INFO": Dst-ip="10.6.99.2" Dst-port="53566" Detail="Sending Response Code=100, Method=INVITE, To=sip:4610003 at 10.6.99.71, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,331" Module="network.sip" Level="DEBUG": Dst-ip="10.6.99.2" Dst-port="53566"
SIPMSG:
|SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2;ingress-zone=DefaultZone
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 INVITE
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>
Server: TANDBERG/4120 (X7.2)
Content-Length: 0
|
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,331" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:8730/directorypolicy/userpolicy.cpl" Ref="0x7fd4dc23b880"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,332" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,333" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.000977" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,334" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="8730" Dst-ip="127.0.0.1" Dst-port="49458" Response="200 OK" ResponseTime="0.002634" Ref="0x7fd4dc23b880"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,335" Module="network.cpl" Level="DEBUG": Remote-ip="10.6.99.2" Remote-port="5060" Detail="CPL: <routed> "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,335" Module="network.cpl" Level="DEBUG": Remote-ip="10.6.99.2" Remote-port="5060" Detail="CPL: <proxy stop-on-busy="no" timeout="0"/> "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,335" Module="network.search" Level="INFO": Detail="Considering search rule 'JFSC' towards target 'HUAWEI-JFSC' at priority '1' with alias '4610003'"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,336" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(79)" Method="SIPToH323Target::SIPToH323Target" Thread="0x7fd4e3c94700": Constructor. MFF = 0x44fbcd0 rDestAddrs="{ ['IPv4''UDP''10.160.0.140:1719'] }"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,336" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(262)" Method="SIPToH323Target::hasDescendant" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,336" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(235)" Method="SIPToH323Target::getPriority" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" m_outboundLegInfo.priority = 1 non-native
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,336" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(235)" Method="SIPToH323Target::getPriority" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" m_outboundLegInfo.priority = 1 non-native
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,336" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(139)" Method="SIPToH323Target::runImpl" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(856)" Method="IIWFTarget::sendSipRequest" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" tranId="8524" rRequest="INVITE sip:4610003 at 10.6.99.71:5060 SIP/2.0
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2;ingress-zone=DefaultZone
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 INVITE
Remote-Party-ID: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;party=calling;screen=yes;privacy=off
Contact: <sip:2972 at 10.6.99.2:5060;transport=tcp>;video;audio;video;bfcp
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>
Max-Forwards: 69
Allow: INVITE,OPTIONS,INFO,BYE,CANCEL,ACK,PRACK,UPDATE,REFER,SUBSCRIBE,NOTIFY
User-Agent: Cisco-CUCM8.6
Expires: 180
Date: Wed, 07 May 2014 18:12:15 GMT
Supported: timer,resource-priority,replaces,X-cisco-srtp-fallback,X-cisco-original-called
Session-Expires: 1800
Min-SE: 1800
Allow-Events: presence
X-TAATag: 38e10662-d612-11e3-8836-0010f3230a42
Cisco-Guid: 0485584640-0000065536-0000001340-0040044042
Content-Length: 0
"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipToH323IdleState.cpp(41)" Method="IWFSipToH323IdleState::sipInviteRequest" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1166)" Method="SIPToH323Target::shouldRouteViaNettle" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" InboundLegConfig="mSipMediaEncryptionMode: Auto"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1192)" Method="SIPToH323Target::shouldControlEncryption" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" controlEncryption="false"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1172)" Method="SIPToH323Target::shouldRouteViaNettle" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" isCall="true" fromClusterPeer="false" fromNettle="false" routeViaNettle="false"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFOfferAnswerIdleState.cpp(27)" Method="IWFOfferAnswerIdleState::sipInviteReceived" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipData.cpp(232)" Method="IWFSipData::changeOfferAnswerState" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" change OAState from IWFOfferAnswerIdleState to IWFIncomingDelayedOffer
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaSinkHandler.cpp(86)" Method="IWFMediaSinkHandler::initialiseSinks" Thread="0x7fd4e3c94700": BFCP supported = true ipnetwork="'IPv4'"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPair_ConfigWithUdpSink_Req Msg"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x5947e50, 0x5957f60; BoundAddrs: 10.6.99.71:0, 10.6.99.71:0)"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaSinkHandler.cpp(252)" Method="IWFMediaSinkHandler::handleMediaHalf" Thread="0x7fd4e3c94700":
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipToH323IdleState.cpp(172)" Method="IWFSipToH323IdleState::sinkInitialisationComplete" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(119)" Method="IWFEncryptionMgr::setSipSupportsTLS" Thread="0x7fd4e3c94700": supportsTLS="false"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(839)" Method="Interworking::extractInfoFromInviteOrAck" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" No SDP found in sip message.
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,337" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(240)" Method="Interworking::conditionallyAddHintMCUToRemoteContactAlias" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipToH323IdleState.cpp(238)" Method="IWFSipToH323IdleState::sinkInitialisationComplete" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Empty invite received
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(78)" Method="Interworking::buildH225SetupFromInvite" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(40)" Method="IWFBandwidthMgr::getSipNoLowerThanDefaultBWUniKbps" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" 2048kbps
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(136)" Method="Interworking::buildH225SetupFromInvite" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Q931 { Message Type: Setup Call reference flag: Message sent from originating side Call reference value: 0x1 Info Element : Bearer Capability { Type: Default Multirate: True Rate Multiplier: 32 Transfer Capability: Unrestricted User Info: H323 } Info Element : User User { Length = 0 } Info Element : Display { Display Information = 2972 at 10.6.99.2 } Info Element : Calling Party Number { Type: Unknown Numbering Plan: Unknown Digits: 2972 } } value H323-UserInformation ::= { h323-uu-pdu { h323-message-body setup : { protocolIdentifier ''H, sourceAddress { h323-ID : "2972 at 10.6.99.2" }, sourceInfo { vendor { vendor { t35CountryCode 130, t35Extension 1, manufacturerCode 256 }, productId '54616E6462657267'H, versionId '34313230'H }, terminal { nonStandardData { nonStandardIdentifier h221NonStandard : { t35CountryCode 130, t35Extension 1, manufacturerCode 256 }, data '54616E6462657267'H } }, mc FALSE, undefinedNode FALSE }, destinationAddress { dialedDigits : "4610003" }, destCallSignalAddress ipAddress : { ip '0AA0008C'H, port 1719 }, activeMC FALSE, conferenceID '38E2AF9ED61211E38CA50010F3230A42'H, conferenceGoal create : NULL, callType pointToPoint : NULL, callIdentifier { guid '38E2AF30D61211E3BB320010F3230A42'H }, mediaWaitForConnect TRUE, canOverlapSend FALSE, multipleCalls FALSE, maintainConnection FALSE, desiredFeatures { { id nonStandard : 'F4BD287D64FA41A3B3372C8C091B4AE2'H } } }, h245Tunneling FALSE, genericData { { id nonStandard : 'DD4AADAB21B611DD8965005056C00008'H, parameters { { id nonStandard : 'DD4AADAD21B611DD8965005056C00008'H, content compound : { { id nonStandard : 'DD4AADAE21B611DD8965005056C00008'H, content text : "893499~c7bd2117-4182-4ada-aa1a-69fa ..." }, { id nonStandard : 'DD4AADAF21B611DD8965005056C00008'H, content text : "1cf16f00-36a1777f-a8f18-263060a at 10. ..." }, { id nonStandard : 'DD4AADB021B611DD8965005056C00008'H, content number32 : 101 } } } } } } } }
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(454)" Method="IIWFTarget::createTargetLeg" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1083)" Method="SIPToH323Target::createH323Target" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" createH323GKTarget
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(340)" Method="SIPToH323Target::getCall" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(528)" Method="SIPToH323Target::getInitialMsg" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(660)" Method="SIPToH323Target::getHopCount" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(357)" Method="SIPToH323Target::needToRouteMedia" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1013)" Method="SIPToH323Target::getUnauthenticatedSourceAliases" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(1020)" Method="SIPToH323Target::getAuthenticatedSourceAliases" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(470)" Method="IIWFTarget::runTargetLeg" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(423)" Method="std::string SIPToH323Target::getViaStack" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,338" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(415)" Method="SIPToH323Target::getLoopDetectionHash" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFSipToH323IdleState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFSipToH323IdleState to IWFAwaitingConnectH323OutLegState. Buffered state = IWFConnectingH323OutLegState
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="1719"
Detail="Sending RAS LRQ SeqNum=39435 DestAlias='4610003' Bandwidth=2048Kb/s SrcAlias='2972 at 10.6.99.2' Hopcount=15 "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="1719"
Sending RAS PDU:
value RasMessage ::= locationRequest :
{
requestSeqNum 39435,
destinationInfo
{
dialedDigits : "4610003"
},
replyAddress ipAddress :
{
ip '0A066347'H,
port 1719
},
sourceInfo
{
h323-ID : "VCSc"
},
canMapAlias TRUE,
genericData
{
{
id nonStandard : 'DD4AADAB21B611DD8965005056C00008'H,
parameters
{
{
id nonStandard : 'DD4AADAC21B611DD8965005056C00008'H,
content raw : '38E10662D61211E388360010F3230A42'H
},
{
id nonStandard : 'DD4AADB121B611DD8965005056C00008'H,
content raw : '5669613A205349502F322E302F5544502031302E362E39392E37313B6272616E63683D ...'H
}
}
}
},
hopCount 15,
callIdentifier
{
guid '38E2C718D61211E3AB430010F3230A42'H
},
bandWidth 40960,
sourceEndpointInfo
{
h323-ID : "2972 at 10.6.99.2"
}
}
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,339" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="1719"
Via Stack:
Via: SIP/2.0/UDP 10.6.99.71;branch=H323.478c793bbb9ba7d5814c4f62fd686b9a
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2;ingress-zone=DefaultZone
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,346" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="1719"
Detail="Received RAS LCF SeqNum=39435 DestAlias='4610003' 'JFSC_MCU' CS address=10.160.0.140:1720 RAS address=10.160.0.140:1719 Bandwidth=2048Kb/s "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,346" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="1719"
Received RAS PDU:
value RasMessage ::= locationConfirm :
{
requestSeqNum 39435,
callSignalAddress ipAddress :
{
ip '0AA0008C'H,
port 1720
},
rasAddress ipAddress :
{
ip '0AA0008C'H,
port 1719
},
nonStandardData
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 28,
t35Extension 21,
manufacturerCode 555
},
data '1038E2C718D61211E3AB430010F3230A42030000'H
},
destinationInfo
{
dialedDigits : "4610003",
h323-ID : "JFSC_MCU"
},
destinationType
{
mcu
{
},
mc FALSE,
undefinedNode FALSE
},
remoteExtensionAddress
{
transportID : ipAddress :
{
ip '0AA0008C'H,
port 1720
}
},
genericData
{
{
id nonStandard : '00000000000000000000260020110829'H,
parameters
{
{
id standard : 0,
content bool : TRUE
}
}
},
{
id nonStandard : '00000000000000000000260020110833'H,
parameters
{
{
id standard : 0,
content number32 : 36
}
}
}
},
bandWidth 40960
}
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,346" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(945)" Method="SIPToH323Target::canProceedWithSetup" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,346" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(340)" Method="SIPToH323Target::getCall" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,347" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(528)" Method="SIPToH323Target::getInitialMsg" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,347" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(357)" Method="SIPToH323Target::needToRouteMedia" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,347" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(908)" Method="SIPToH323Target::replaceChildAndRun" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,347" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(685)" Method="IIWFTarget::destroyTarget" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,348" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:9999/licensemanager/acquire" Ref="0x7fd4dc2245b0"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,352" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc UTCTime="2014-05-07 18:05:50,352" Module="developer.licensemanager.service.manager" Level="INFO" CodeLocation="licensemanager(132)" Detail="License granted" call_id="38e1059a-d612-11e3-8209-0010f3230a42" lic_type="traversal"
2014-05-07T15:05:50-03:00 vcsc licensemanager: Level="INFO" Detail="License granted" call_id="38e1059a-d612-11e3-8209-0010f3230a42" lic_type="traversal" UTCTime="2014-05-07 18:05:50,352"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,353" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001081" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,354" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="9999" Dst-ip="127.0.0.1" Dst-port="43192" Response="200 OK" ResponseTime="0.005651" Ref="0x7fd4dc2245b0"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,355" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15018" Dst-ip="10.160.0.140" Dst-port="1720" Detail="TCP Connecting"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,356" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15018" Dst-ip="10.160.0.140" Dst-port="1720" Detail="TCP Connection Established"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,356" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(156)" Method="IWFEncryptionMgr::getInterworkingEncryptionMode" Thread="0x7fd4e3c94700": mode = INTERWORKING_ENCRYPTION_MODE_AUTO
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,356" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(156)" Method="IWFEncryptionMgr::getInterworkingEncryptionMode" Thread="0x7fd4e3c94700": mode = INTERWORKING_ENCRYPTION_MODE_AUTO
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,356" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="1720"
Detail="Sending H.225 Setup H.323v6 Bandwidth:2048kbps DestAlias:4610003 DestCSAddr:['IPv4''TCP''10.160.0.140:1720'] Vendor:TANDBERG"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,357" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="1720"
Sending H.225 PDU:
Q931
{
Message Type: Setup
Call reference flag: Message sent from originating side
Call reference value: 0xa
Info Element : Bearer Capability
{
Type: Default
Multirate: True
Rate Multiplier: 32
Transfer Capability: Unrestricted
User Info: H323
}
Info Element : Display
{
Display Information = 2972 at 10.6.99.2
}
Info Element : Calling Party Number
{
Type: Unknown
Numbering Plan: Unknown
Digits: 2972
}
Info Element : Called Party Number
{
Type: Unknown
Numbering Plan: Unknown
Digits: 4610003
}
Info Element : User User
{
Length = 395
}
}
value H323-UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 6 },
sourceAddress
{
h323-ID : "2972 at 10.6.99.2"
},
sourceInfo
{
vendor
{
vendor
{
t35CountryCode 130,
t35Extension 1,
manufacturerCode 256
},
productId '54616E6462657267'H,
versionId '34313230'H
},
terminal
{
nonStandardData
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 130,
t35Extension 1,
manufacturerCode 256
},
data '54616E6462657267'H
}
},
mc FALSE,
undefinedNode FALSE
},
destinationAddress
{
dialedDigits : "4610003"
},
destCallSignalAddress ipAddress :
{
ip '0AA0008C'H,
port 1720
},
activeMC FALSE,
conferenceID '38E2AF9ED61211E38CA50010F3230A42'H,
conferenceGoal create : NULL,
callType pointToPoint : NULL,
sourceCallSignalAddress ipAddress :
{
ip '0A066347'H,
port 15018
},
callIdentifier
{
guid '38E2C718D61211E3AB430010F3230A42'H
},
mediaWaitForConnect TRUE,
canOverlapSend FALSE,
multipleCalls FALSE,
maintainConnection FALSE,
desiredFeatures
{
{
id nonStandard : 'F4BD287D64FA41A3B3372C8C091B4AE2'H
}
}
},
h245Tunneling FALSE,
genericData
{
{
id nonStandard : 'DD4AADAB21B611DD8965005056C00008'H,
parameters
{
{
id nonStandard : 'DD4AADAC21B611DD8965005056C00008'H,
content raw : '38E10662D61211E388360010F3230A42'H
},
{
id nonStandard : 'DD4AADAD21B611DD8965005056C00008'H,
content compound :
{
{
id nonStandard : 'DD4AADAE21B611DD8965005056C00008'H,
content text : "893499~c7bd2117-4182-4ada-aa1a-69fa ..."
},
{
id nonStandard : 'DD4AADAF21B611DD8965005056C00008'H,
content text : "1cf16f00-36a1777f-a8f18-263060a at 10. ..."
},
{
id nonStandard : 'DD4AADB021B611DD8965005056C00008'H,
content number32 : 101
}
}
}
}
}
}
}
}
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,362" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,363" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001111" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,386" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="1720"
Detail="Received H.225 Proceeding "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,386" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="1720"
Received H.225 PDU:
Q931
{
Message Type: Call Proceeding
Call reference flag: Message sent to originating side
Call reference value: 0xa
Info Element : Display
{
Display Information = Huawei Technologies Co., Ltd.
}
Info Element : User User
{
Length = 40
}
}
value H323-UserInformation ::=
{
h323-uu-pdu
{
h323-message-body callProceeding :
{
protocolIdentifier { 0 0 8 2250 0 6 },
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 38,
t35Extension 0,
manufacturerCode 8209
},
productId '48756157656920436F727000'H,
versionId '56312E3000'H
},
gatekeeper
{
},
mc FALSE,
undefinedNode FALSE
},
callIdentifier
{
guid '38E2C718D61211E3AB430010F3230A42'H
},
multipleCalls TRUE,
maintainConnection FALSE
},
h245Tunneling FALSE
}
}
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,386" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(860)" Method="SIPToH323Target::provisionalResponseReceived" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,386" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(548)" Method="IWFState::h225Proceeding" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="1720"
Detail="Received H.225 Connect "
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="1720"
Received H.225 PDU:
Q931
{
Message Type: Connect
Call reference flag: Message sent to originating side
Call reference value: 0xa
Info Element : Bearer Capability
{
Type: Default
Multirate: True
Rate Multiplier: 32
Transfer Capability: Unrestricted
}
Info Element : Display
{
Display Information = Huawei Technologies Co., Ltd.
}
Info Element : User User
{
Length = 149
}
}
value H323-UserInformation ::=
{
h323-uu-pdu
{
h323-message-body connect :
{
protocolIdentifier { 0 0 8 2250 0 6 },
h245Address ipAddress :
{
ip '0AA0008C'H,
port 30085
},
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 38,
t35Extension 0,
manufacturerCode 8209
},
productId '56696577506F696E742038363530'H,
versionId '56313030523030384330314230313753503130202852656C6561736520382E312E3137 ...'H
},
mcu
{
},
mc FALSE,
undefinedNode FALSE
},
conferenceID '38E2AF9ED61211E38CA50010F3230A42'H,
callIdentifier
{
guid '38E2C718D61211E3AB430010F3230A42'H
},
multipleCalls TRUE,
maintainConnection FALSE
},
h245Tunneling FALSE,
genericData
{
{
id nonStandard : '00000000000000000000260020110001'H,
parameters
{
{
id standard : 0,
content bool : FALSE
}
}
}
}
}
}
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(488)" Method="SIPToH323Target::canProceedWithTargetAcquired" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(1009)" Method="IWFState::canProceedWithTargetAcquired" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(601)" Method="IIWFTarget::h323LegTargetAcquiredWinner" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(877)" Method="SIPToH323Target::targetAcquired" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingConnectH323OutLegState.cpp(111)" Method="IWFAwaitingConnectH323OutLegState::targetAcquired" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Ignored
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(1017)" Method="IWFState::targetLegAssociateToCall" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingConnectH323OutLegState.cpp(51)" Method="IWFAwaitingConnectH323OutLegState::h225Connect" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(112)" Method="IWFEncryptionMgr::setH323SetupConnectSupportsAES" Thread="0x7fd4e3c94700": m_h323SetupConnectSupportsAES="false"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(76)" Method="IWFBandwidthMgr::setH323BandwidthUniKbps" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" h323:2048kbps sip:0kbps default:2048kbps graph:2048kbps
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(102)" Method="IWFBandwidthMgr::updateBandwidthGraph" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" No need to change call bandwith, remaining at 2048kbps
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(267)" Method="IIWFTarget::connectH245ToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,946" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15019" Dst-ip="10.160.0.140" Dst-port="30085" Detail="TCP Connecting"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15019" Dst-ip="10.160.0.140" Dst-port="30085" Detail="TCP Connection Established"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingConnectH323OutLegState.cpp(81)" Method="IWFAwaitingConnectH323OutLegState::connectH245Success" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingConnectH323OutLegState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFAwaitingConnectH323OutLegState to IWFAwaitingTCSState. Buffered state = IWFConnectingH323OutLegState
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,947" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(156)" Method="IWFEncryptionMgr::getInterworkingEncryptionMode" Thread="0x7fd4e3c94700": mode = INTERWORKING_ENCRYPTION_MODE_AUTO
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,952" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:50-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:50,953" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001071" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="30085"
Detail="Received H.245 TerminalCapabilitySetRequest "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="30085"
Received H.245 PDU:
value MultimediaSystemControlMessage ::= request : terminalCapabilitySet :
{
sequenceNumber 0,
protocolIdentifier { 0 0 8 245 0 10 },
multiplexCapability h2250Capability :
{
maximumAudioDelayJitter 60,
receiveMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl TRUE,
distributedControl FALSE,
centralizedAudio TRUE,
distributedAudio FALSE,
centralizedVideo TRUE,
distributedVideo FALSE
}
}
},
transmitMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl TRUE,
distributedControl FALSE,
centralizedAudio TRUE,
distributedAudio FALSE,
centralizedVideo TRUE,
distributedVideo FALSE
}
}
},
receiveAndTransmitMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl TRUE,
distributedControl FALSE,
centralizedAudio TRUE,
distributedAudio FALSE,
centralizedVideo TRUE,
distributedVideo FALSE
}
}
},
mcCapability
{
centralizedConferenceMC TRUE,
decentralizedConferenceMC FALSE
},
rtcpVideoControlCapability FALSE,
mediaPacketizationCapability
{
h261aVideoPacketization FALSE,
rtpPayloadType
{
{
payloadDescriptor oid : { 0 0 8 241 0 0 0 0 }
}
}
}
},
capabilityTable
{
{
capabilityTableEntryNumber 1,
capability receiveAndTransmitAudioCapability : g722-64k : 20
},
{
capabilityTableEntryNumber 2,
capability receiveAndTransmitAudioCapability : g722-56k : 20
},
{
capabilityTableEntryNumber 3,
capability receiveAndTransmitAudioCapability : g722-48k : 20
},
{
capabilityTableEntryNumber 4,
capability receiveAndTransmitAudioCapability : g711Alaw64k : 20
},
{
capabilityTableEntryNumber 5,
capability receiveAndTransmitAudioCapability : g711Ulaw64k : 20
},
{
capabilityTableEntryNumber 6,
capability receiveAndTransmitAudioCapability : genericAudioCapability :
{
capabilityIdentifier standard : { 0 0 8 245 1 1 11 },
maxBitRate 160,
collapsing
{
{
parameterIdentifier standard : 0,
parameterValue unsignedMin : 0
},
{
parameterIdentifier standard : 1,
parameterValue unsignedMax : 20
}
}
}
},
{
capabilityTableEntryNumber 7,
capability receiveAndTransmitAudioCapability : g729AnnexA : 20
},
{
capabilityTableEntryNumber 8,
capability receiveAndTransmitAudioCapability : g729AnnexAwAnnexB : 20
},
{
capabilityTableEntryNumber 9,
capability receiveVideoCapability : genericVideoCapability :
{
capabilityIdentifier standard : { 0 0 8 241 0 0 1 },
maxBitRate 20480,
collapsing
{
{
parameterIdentifier standard : 41,
parameterValue booleanArray : 64
},
{
parameterIdentifier standard : 42,
parameterValue unsignedMin : 85
},
{
parameterIdentifier standard : 3,
parameterValue unsignedMin : 492
},
{
parameterIdentifier standard : 4,
parameterValue unsignedMin : 32
}
}
}
},
{
capabilityTableEntryNumber 10,
capability receiveVideoCapability : h263VideoCapability :
{
cif4MPI 1,
maxBitRate 20480,
unrestrictedVector FALSE,
arithmeticCoding FALSE,
advancedPrediction FALSE,
pbFrames FALSE,
temporalSpatialTradeOffCapability FALSE,
errorCompensation FALSE,
enhancementLayerInfo
{
baseBitRateConstrained FALSE
}
}
},
{
capabilityTableEntryNumber 11,
capability receiveVideoCapability : h261VideoCapability :
{
cifMPI 1,
temporalSpatialTradeOffCapability FALSE,
maxBitRate 19200,
stillImageTransmission FALSE
}
},
{
capabilityTableEntryNumber 14,
capability receiveAndTransmitDataApplicationCapability :
{
application h224 : hdlcFrameTunnelling : NULL,
maxBitRate 640
}
},
{
capabilityTableEntryNumber 15,
capability receiveUserInputCapability : basicString : NULL
},
{
capabilityTableEntryNumber 16,
capability receiveUserInputCapability : dtmf : NULL
},
{
capabilityTableEntryNumber 17,
capability receiveRTPAudioTelephonyEventCapability :
{
dynamicRTPPayloadType 97,
audioTelephoneEvent "0123456789*#ABCD"
}
},
{
capabilityTableEntryNumber 18,
capability nonStandard :
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 28,
t35Extension 21,
manufacturerCode 555
},
data '00000001000000080101000000000000'H
}
},
{
capabilityTableEntryNumber 19,
capability nonStandard :
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 38,
t35Extension 0,
manufacturerCode 8209
},
data '0000000100000018000000000000000452545254000000030000000400000A24'H
}
},
{
capabilityTableEntryNumber 20,
capability nonStandard :
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 38,
t35Extension 0,
manufacturerCode 8210
},
data '000000020000001800000000000000044D434D43000000040000000400000006'H
}
},
{
capabilityTableEntryNumber 21,
capability conferenceCapability :
{
chairControlCapability TRUE
}
},
{
capabilityTableEntryNumber 12,
capability receiveAndTransmitVideoCapability : extendedVideoCapability :
{
videoCapability
{
genericVideoCapability :
{
capabilityIdentifier standard : { 0 0 8 241 0 0 1 },
maxBitRate 3840,
collapsing
{
{
parameterIdentifier standard : 41,
parameterValue booleanArray : 64
},
{
parameterIdentifier standard : 42,
parameterValue unsignedMin : 43
}
}
}
},
videoCapabilityExtension
{
{
capabilityIdentifier standard : { 0 0 8 239 1 2 },
collapsing
{
{
parameterIdentifier standard : 1,
parameterValue booleanArray : 3
}
}
}
}
}
},
{
capabilityTableEntryNumber 13,
capability genericControlCapability :
{
capabilityIdentifier standard : { 0 0 8 239 1 1 }
}
}
},
capabilityDescriptors
{
{
capabilityDescriptorNumber 1,
simultaneousCapabilities
{
{
1,
2,
3,
4,
5,
6,
7,
8
},
{
9,
10,
11
},
{
12
},
{
14
},
{
15,
16,
17
},
{
18
},
{
19
},
{
20
},
{
21
}
}
}
}
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(249)" Method="Interworking::sendH245TCSAck" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(244)" Method="IIWFTarget::sendH245MsgToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Sending H245Msg =
value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : { sequenceNumber 0 }
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(708)" Method="SIPToH323Target::getVendorBlock" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingTCSState.cpp(23)" Method="IWFAwaitingTCSState::h245TCSRequest" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(89)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Timer = TIMER_H323_TCS_T101
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(52)" Method="IWFCapsManager::setH323CapSet" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" CapSet Before storing: Capset contains 15 elements, packing support for: rfc3984nal
1: G.722_64 [255] clockRate: 8000 maxMsec:20
2: G.722_56 [255] clockRate: 0 maxMsec:20
3: G.722_48 [255] clockRate: 0 maxMsec:20
4: G.711A_64 [255] clockRate: 8000 maxMsec:20
5: G.711Mu_64 [255] clockRate: 8000 maxMsec:20
7: G.729A [255] clockRate: 8000 maxMsec:20
8: G.729AB [255] clockRate: 8000 maxMsec:20
9: H.264 [255] clockRate:90000 @ 2048.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:4 maxBR:240000 maxMBPS:492 maxFS:8192 maxDPB:0 maxCPB:0 maxStaticMBPS:492
10: H.263 [255] clockRate:90000 @ 2048.0kbit/s, 4cif:1/30
11: H.261 [255] clockRate:90000 @ 1920.0kbit/s, cif:1/30
14: H.224 [255]
16: DTMF
21: CIC
12: H.264 [255] clockRate:90000 Duo-Presentation @ 384.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:2 maxBR:24000 maxMBPS:24 maxFS:396 maxDPB:0 maxCPB:0 maxStaticMBPS:24
13: H.239 Control
{
1 : { 0:[ 1 2 3 4 5 7 8 ] 1:[ 9 10 11 ] 2:[ 12 ] 3:[ 14 ] 4:[ 16 ] 5:[ 21 ] }
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,126" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(61)" Method="IWFCapsManager::setH323CapSet" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Stored capsset: Capset contains 10 elements
1: H.264 [255] clockRate:90000 @ 2048.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:4 maxBR:240000 maxMBPS:492 maxFS:8192 maxDPB:0 maxCPB:0 maxStaticMBPS:492
2: H.263 [255] clockRate:90000 @ 2048.0kbit/s, 4cif:1/30
3: H.261 [255] clockRate:90000 @ 1920.0kbit/s, cif:1/30
4: H.264 [255] clockRate:90000 Duo-Presentation @ 384.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:2 maxBR:24000 maxMBPS:24 maxFS:396 maxDPB:0 maxCPB:0 maxStaticMBPS:24
5: G.722_64 [255] clockRate: 8000 maxMsec:20
8: G.711A_64 [255] clockRate: 8000 maxMsec:20
9: G.711Mu_64 [255] clockRate: 8000 maxMsec:20
10: H.239 Control
12: H.224 [255]
13: DTMF [255] clockRate: 8000 maxMsec:40 first:0 last:15
{
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(518)" Method="IIWFTarget::sendTargetAcquiredToParent" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFIncomingDelayedOffer.cpp(40)" Method="IWFIncomingDelayedOffer::doSendSip200OK" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFOfferAnswerFuncs.cpp(84)" Method="Interworking::sendSip200OkWithSdpOffer" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(58)" Method="IWFBandwidthMgr::getH323NoHigherThanGraphBWUniKbps" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" 2048kbps
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(84)" Method="IWFEncryptionMgr::canAddEncryptionToSDP" Thread="0x7fd4e3c94700": canAddEncryptionToSDP="false" m_h323SetupConnectSupportsAES="false" m_sipSupportsTLS="false" m_h323CapsSupportsAES="false" m_zoneProfileSupportsEncryption="true"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(100)" Method="IWFCapsManager::h323HasCallOnHold" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" h323HasCallOnHold="0"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf.offeranswer" Level="DEBUG" CodeLocation="ppcmains/sip/offeranswer/OfferAnswerImpl.cpp(157)" Method="OfferAnswerImpl::generateOffer" Thread="0x7fd4e3c94700": Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" filteredCaps="
BandwidthBps: 2048000
SessionKey: branchId = 0, streamtype = STREAM_TYPE_VIDEO, streamNum = 1
Direction: DIRECTION_SEND_RECEIVE
BandwidthBps: 2048000
Encryption: encrypted: false mandatory: false
Feedback attributes: a=rtcp-fb:* ccm tmmbr
1: H.264 [255] clockRate:90000 @ 2048.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:4 maxBR:240000 maxMBPS:492 maxFS:8192 maxDPB:0 maxCPB:0 maxStaticMBPS:492
2: H.263 [255] clockRate:90000 @ 2048.0kbit/s, 4cif:1/30
3: H.261 [255] clockRate:90000 @ 1920.0kbit/s, cif:1/30
SessionKey: branchId = 0, streamtype = STREAM_TYPE_DUO_VIDEO, streamNum = 1
Direction: DIRECTION_SEND_RECEIVE
BandwidthBps: 384000
Encryption: encrypted: false mandatory: false
Feedback attributes: a=rtcp-fb:* ccm tmmbr
4: H.264 [255] clockRate:90000 @ 384.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:2 maxBR:24000 maxMBPS:24 maxFS:396 maxDPB:0 maxCPB:0 maxStaticMBPS:24
SessionKey: branchId = 0, streamtype = STREAM_TYPE_AUDIO, streamNum = 1
Direction: DIRECTION_SEND_RECEIVE
BandwidthBps: 64000
Encryption: encrypted: false mandatory: false
Feedback attributes:
5: G.722_64 [255] clockRate: 8000 maxMsec:20
8: G.711A_64 [255] clockRate: 8000 maxMsec:20
9: G.711Mu_64 [255] clockRate: 8000 maxMsec:20
13: DTMF [255] clockRate: 8000 maxMsec:40 first:0 last:15
SessionKey: branchId = 0, streamtype = STREAM_TYPE_BFCP, streamNum = 1
Direction: DIRECTION_SEND_RECEIVE
BandwidthBps: 0
Encryption: encrypted: false mandatory: false
Feedback attributes:
eFloorCtrRole: FLOORCTRLROLE_CS eSetupRole: SETUPROLE_PASSIVE eConnVal: CONNVAL_NEW iConferenceID: 950991991 iUserID: 14564 iFloorID: 48916
SessionKey: branchId = 0, streamtype = STREAM_TYPE_H224, streamNum = 1
Direction: DIRECTION_SEND_RECEIVE
BandwidthBps: 0
Encryption: encrypted: false mandatory: false
Feedback attributes:
12: H.224 [255]
" localOfferMedialines="
connectionAddress: ['IPv4''UNDEF''127.0.0.1']
bandwidthUniBps: 2048000
sessionVersion: 1
ice lite: false
activeLines: 5
medialine index: 0
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_AUDIO
szType: audio
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels:
answer modes:
label:
connAddr: ['UNDEF''UNDEF''Error As String']
rtcpAddr['UNDEF''UNDEF''Error As String']
tias: 64000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 4
0 = 5: G.722_64 [ 9] clockRate: 8000 maxMsec:20
1 = 8: G.711A_64 [ 8] clockRate: 8000 maxMsec:20
2 = 9: G.711Mu_64 [ 0] clockRate: 8000 maxMsec:20
3 = 13: DTMF [101] clockRate: 8000 maxMsec:40 first:0 last:15
medialine index: 1
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_VIDEO
szType: video
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels: CONTENT_MAIN
answer modes:
label: 11
connAddr: ['UNDEF''UNDEF''Error As String']
rtcpAddr['UNDEF''UNDEF''Error As String']
tias: 2048000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList: a=rtcp-fb:* ccm tmmbr
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 3
0 = 1: H.264 [ 96] clockRate:90000 @ 2048.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:4 maxBR:240000 maxMBPS:492 maxFS:8192 maxDPB:0 maxCPB:0 maxStaticMBPS:492
1 = 2: H.263 [ 34] clockRate:90000 @ 2048.0kbit/s, 4cif:1/30
2 = 3: H.261 [ 31] clockRate:90000 @ 1920.0kbit/s, cif:1/30
medialine index: 2
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_VIDEO
szType: video
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels: CONTENT_SLIDES
answer modes:
label: 12
connAddr: ['UNDEF''UNDEF''Error As String']
rtcpAddr['UNDEF''UNDEF''Error As String']
tias: 384000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList: a=rtcp-fb:* ccm tmmbr
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 1
0 = 4: H.264 [ 96] clockRate:90000 @ 384.0kbit/s, iProfile:64 iPacketizationMode:0 iConstraintSet:0 defaultLevel:2 maxBR:24000 maxMBPS:24 maxFS:396 maxDPB:0 maxCPB:0 maxStaticMBPS:24
medialine index: 3
rejected: false
mediaClass: SDP_MF_BFCP
eType: SDP_MEDIA_TYPE_APPLICATION
szType: application
trans: SDP_MEDIA_TRANS_UDP_BFCP
szTransport: UDP/BFCP
direction: SDP_MEDIA_DIR_SENDRECV
content labels:
answer modes:
label:
connAddr: ['UNDEF''UNDEF''Error As String']
rtcpAddr['UNDEF''UNDEF''Error As String']
eFloorCtrRole: FLOORCTRLROLE_CS eSetupRole: SETUPROLE_PASSIVE eConnVal: CONNVAL_NEW iConferenceID: 950991991 iUserID: 14564 iFloorID: 48916 iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 0
medialine index: 4
rejected: false
mediaClass: SDP_MF_H224
eType: SDP_MEDIA_TYPE_APPLICATION
szType: application
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels:
answer modes:
label:
connAddr: ['UNDEF''UNDEF''Error As String']
rtcpAddr['UNDEF''UNDEF''Error As String']
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 1
0 = 12: H.224 [ 96]
"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(224)" Method="Interworking::conditionallyAddisfocusToContact" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,127" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(355)" Method="IIWFTarget::sendSipAnswerResponseToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" tranId="8524" rResponse="SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2;ingress-zone=DefaultZone
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 INVITE
Contact: <sip:4610003 at 10.6.99.71:5060>;isfocus
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
Record-Route: <sip:proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42 at 10.6.99.71:5060;transport=tcp;lr>
Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFY
Server: TANDBERG/4120 (X7.2)
Content-Type: application/sdp
Content-Length: 1031
v=0
o=tandberg 0 1 IN IP4 127.0.0.1
s=-
c=IN IP4 127.0.0.1
b=AS:2048
t=0 0
m=audio 1024 RTP/AVP 9 8 0 101
b=TIAS:64000
a=rtpmap:9 G722/8000
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:1024 IN IP4 127.0.0.1
m=video 1024 RTP/AVP 96 34 31
b=TIAS:2048000
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428028;max-mbps=246000;max-smbps=246000
a=rtpmap:34 H263/90000
a=fmtp:34 cif4=1;maxbr=20480
a=rtpmap:31 H261/90000
a=fmtp:31 cif=1;maxbr=19200
a=rtcp-fb:* ccm tmmbr
a=sendrecv
a=content:main
a=label:11
a=rtcp:1024 IN IP4 127.0.0.1
m=video 1024 RTP/AVP 96
b=TIAS:384000
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428014
a=rtcp-fb:* ccm tmmbr
a=sendrecv
a=content:slides
a=label:12
a=rtcp:1024 IN IP4 127.0.0.1
m=application 1024 UDP/BFCP *
a=floorctrl:c-s
a=confid:950991991
a=floorid:48916 mstrm:12
a=userid:14564
a=setup:passive
a=connection:new
m=application 1024 RTP/AVP 96
a=rtpmap:96 H224/4800
a=sendrecv
"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFIncomingOfferBase.cpp(58)" Method="IWFIncomingOfferBase::startSip200OKRetransmitTimerExpire" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Starting 200OK retrans timer, will fire in 500MS
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(481)" Method="IIWFTarget::associateIwfLegToCall" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingTCSState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFAwaitingTCSState to IWFAwaitingAckWithSdpAnswerState. Buffered state = IWFSipConnectedConnectingH323State
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="30085"
Detail="Sending H.245 TerminalCapabilitySetAckResponse "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="30085"
Sending H.245 PDU:
value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck :
{
sequenceNumber 0
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(831)" Method="SIPToH323Target::registerDestructionObserver" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,128" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(156)" Method="IWFEncryptionMgr::getInterworkingEncryptionMode" Thread="0x7fd4e3c94700": mode = INTERWORKING_ENCRYPTION_MODE_AUTO
2014-05-07T15:05:51-03:00 vcsc tvcs: Event="Call Connected" Service="SIP" Src-ip="10.6.99.2" Src-port="5060" Src-alias-type="SIP" Src-alias="sip:2972 at 10.6.99.2" Dst-alias-type="SIP" Dst-alias="sip:4610003 at 10.6.99.71:5060" Call-serial-number="38e104be-d612-11e3-bf3a-0010f3230a42" Tag="38e10662-d612-11e3-8836-0010f3230a42" Protocol="TCP" Call-routed="YES" Level="1" UTCTime="2014-05-07 18:05:51,129"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(190)" Method="SIPToH323Target::cancelOutstandingRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(575)" Method="IIWFTarget::sendCancelCompleteToParent" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairConfigReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x59911b0, 0x59a13e0; BoundAddrs: 10.6.99.71:50110, 10.6.99.71:50111)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairConfigReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x59b1610, 0x59c1840; BoundAddrs: 10.6.99.71:50112, 10.6.99.71:50113)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairConfigReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x59d1a70, 0x59e1ca0; BoundAddrs: 10.6.99.71:50114, 10.6.99.71:50115)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairConfigReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x59f1ed0, 0x5a02100; BoundAddrs: 10.6.99.71:50116, 10.6.99.71:50117)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairConfigReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,130" Module="network.mediarouting" Level="DEBUG": Detail="Sending ConfigCnf Msg (SocketIds: 0x5a12330, 0x5a22560; BoundAddrs: 10.6.99.71:50118, 10.6.99.71:50119)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,131" Module="network.mediarouting" Level="DEBUG": Detail="Route: Storing at index 0: Packets Rxd on 10.6.99.71:50116 (socket 0x59f1ed0) from UNDEFINED Txd on 10.6.99.71:0 (socket 0x5947e50) to 127.0.0.1:0 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,131" Module="network.mediarouting" Level="DEBUG": Detail="Route: Storing at index 1: Packets Rxd on 10.6.99.71:50117 (socket 0x5a02100) from UNDEFINED Txd on 10.6.99.71:0 (socket 0x5957f60) to 127.0.0.1:0 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(454)" Method="IWFState::sipSdpManipulationComplete" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(367)" Method="IWFMediaManager::insertHDLCFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" inserting HDLCFilters
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(299)" Method="IWFMediaManager::sendPayloadMonitoringRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Session Key="branchId = 1, streamtype = STREAM_TYPE_AUDIO, streamNum = 1" Payload Numbers="0 8 9 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(299)" Method="IWFMediaManager::sendPayloadMonitoringRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Session Key="branchId = 1, streamtype = STREAM_TYPE_VIDEO, streamNum = 1" Payload Numbers="31 34 96 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(299)" Method="IWFMediaManager::sendPayloadMonitoringRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Session Key="branchId = 1, streamtype = STREAM_TYPE_DUO_VIDEO, streamNum = 1" Payload Numbers="96 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(299)" Method="IWFMediaManager::sendPayloadMonitoringRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Session Key="branchId = 1, streamtype = STREAM_TYPE_BFCP, streamNum = 1" Payload Numbers=""
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(299)" Method="IWFMediaManager::sendPayloadMonitoringRequests" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Session Key="branchId = 1, streamtype = STREAM_TYPE_H224, streamNum = 1" Payload Numbers="96 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(406)" Method="IWFMediaManager::insertSipRXDropFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding Drop filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_AUDIO, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(406)" Method="IWFMediaManager::insertSipRXDropFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding Drop filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_VIDEO, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(406)" Method="IWFMediaManager::insertSipRXDropFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding Drop filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_DUO_VIDEO, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(406)" Method="IWFMediaManager::insertSipRXDropFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding Drop filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_H224, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(211)" Method="IWFMediaManager::insertSSRCFilterForMediaHalf" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding SSRC filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_VIDEO, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(233)" Method="IWFMediaManager::insertRTCPFilterForMediaHalf" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding RTCP Rx filter to media half Class Type: UniqueMediaHalfImpl rtp local addr: ['IPv4''UDP''10.6.99.71:50112'] rtcp local addr: ['IPv4''UDP''10.6.99.71:50113'] rtp remote tx addr: ['UNDEF''UNDEF''Error As String'] rtcp remote tx addr: ['UNDEF''UNDEF''Error As String'] rtp remote rx addr: ['UNDEF''UNDEF''Error As String'] rtcp remote rx addr: ['UNDEF''UNDEF''Error As String'] rtp probeId: INVALID_ID rtcp probeId: INVALID_ID multiplexId: INVALID_ID policy type: enMatchOnAnySourceAddress rtp isTxInfoComplete: false rtcp isTxInfoComplete: false SessionKey: branchId = 1, streamtype = STREAM_TYPE_VIDEO, streamNum = 1 MediaHalf Type: MEDIAHALF_UNIQUE MediaHalf Fsm: 56 SocketPair Fsm: 2 PacketFilterList: RTP Rx::RTPDropFilter(enabled:true):RTPSSRCObserverFilter:Tx::RTCP Rx::Tx:: packetTypeToMonitor="Transport layer FB message (RTPFB)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(211)" Method="IWFMediaManager::insertSSRCFilterForMediaHalf" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding SSRC filter sessionKey="branchId = 1, streamtype = STREAM_TYPE_DUO_VIDEO, streamNum = 1"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(233)" Method="IWFMediaManager::insertRTCPFilterForMediaHalf" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Adding RTCP Rx filter to media half Class Type: UniqueMediaHalfImpl rtp local addr: ['IPv4''UDP''10.6.99.71:50114'] rtcp local addr: ['IPv4''UDP''10.6.99.71:50115'] rtp remote tx addr: ['UNDEF''UNDEF''Error As String'] rtcp remote tx addr: ['UNDEF''UNDEF''Error As String'] rtp remote rx addr: ['UNDEF''UNDEF''Error As String'] rtcp remote rx addr: ['UNDEF''UNDEF''Error As String'] rtp probeId: INVALID_ID rtcp probeId: INVALID_ID multiplexId: INVALID_ID policy type: enMatchOnAnySourceAddress rtp isTxInfoComplete: false rtcp isTxInfoComplete: false SessionKey: branchId = 1, streamtype = STREAM_TYPE_DUO_VIDEO, streamNum = 1 MediaHalf Type: MEDIAHALF_UNIQUE MediaHalf Fsm: 57 SocketPair Fsm: 3 PacketFilterList: RTP Rx::RTPDropFilter(enabled:true):RTPSSRCObserverFilter:Tx::RTCP Rx::Tx:: packetTypeToMonitor="Transport layer FB message (RTPFB)"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(486)" Method="IWFMediaManager::insertTelephoneEventFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" inserting Telephone Event Monitor
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,132" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMediaManager.cpp(505)" Method="IWFMediaManager::insertTelephoneEventFilters" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" inserting Telephone Event Generator
2014-05-07T15:05:51-03:00 vcsc tvcs: Event="Search Completed" Service="SIP" Src-alias-type="SIP" Src-alias="2972 at 10.6.99.2" Dst-alias-type="SIP" Dst-alias="sip:4610003 at 10.6.99.71:5060" Call-serial-number="38e104be-d612-11e3-bf3a-0010f3230a42" Tag="38e10662-d612-11e3-8836-0010f3230a42" Detail="found:true, searchtype:INVITE" Call-routed="YES" Level="1" UTCTime="2014-05-07 18:05:51,133"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,133" Module="network.sip" Level="INFO": Dst-ip="10.6.99.2" Dst-port="53566" Detail="Sending Response Code=200, Method=INVITE, To=sip:4610003 at 10.6.99.71, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,133" Module="network.sip" Level="DEBUG": Dst-ip="10.6.99.2" Dst-port="53566"
SIPMSG:
|SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91fe474de3b0;received=10.6.99.2;ingress-zone=DefaultZone
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 INVITE
Contact: <sip:4610003 at 10.6.99.71:5060>;isfocus
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
Record-Route: <sip:proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42 at 10.6.99.71:5060;transport=tcp;lr>
Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFY
Server: TANDBERG/4120 (X7.2)
Require: timer
Session-Expires: 1800;refresher=uac
Content-Type: application/sdp
Content-Length: 1043
v=0
o=tandberg 0 1 IN IP4 127.0.0.1
s=-
c=IN IP4 10.6.99.71
b=AS:2048
t=0 0
m=audio 50110 RTP/AVP 9 8 0 101
b=TIAS:64000
a=rtpmap:9 G722/8000
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:50111 IN IP4 10.6.99.71
m=video 50112 RTP/AVP 96 34 31
b=TIAS:2048000
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428028;max-mbps=246000;max-smbps=246000
a=rtpmap:34 H263/90000
a=fmtp:34 cif4=1;maxbr=20480
a=rtpmap:31 H261/90000
a=fmtp:31 cif=1;maxbr=19200
a=rtcp-fb:* ccm tmmbr
a=sendrecv
a=content:main
a=label:11
a=rtcp:50113 IN IP4 10.6.99.71
m=video 50114 RTP/AVP 96
b=TIAS:384000
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428014
a=rtcp-fb:* ccm tmmbr
a=sendrecv
a=content:slides
a=label:12
a=rtcp:50115 IN IP4 10.6.99.71
m=application 50116 UDP/BFCP *
a=floorctrl:c-s
a=confid:950991991
a=floorid:48916 mstrm:12
a=userid:14564
a=setup:passive
a=connection:new
m=application 50118 RTP/AVP 96
a=rtpmap:96 H224/4800
a=sendrecv
|
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,134" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,135" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001124" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,141" Module="network.sip" Level="INFO": Src-ip="10.6.99.2" Src-port="53566" Detail="Receive Request Method=ACK, Request-URI=sip:4610003 at 10.6.99.71:5060, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,141" Module="network.sip" Level="DEBUG": Src-ip="10.6.99.2" Src-port="53566"
SIPMSG:
|ACK sip:4610003 at 10.6.99.71:5060 SIP/2.0
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91ff7b49a6b9;received=10.6.99.2
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 ACK
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
Max-Forwards: 70
Route: <sip:proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42 at 10.6.99.71:5060;transport=tcp;lr>
Date: Wed, 07 May 2014 18:12:15 GMT
Allow-Events: presence
Content-Type: application/sdp
Content-Length: 844
v=0
o=CiscoSystemsCCM-SIP 893499 1 IN IP4 10.6.99.2
s=SIP Call
b=AS:384
t=0 0
m=audio 18444 RTP/AVP 0 101
c=IN IP4 10.6.12.182
b=TIAS:64000
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 26574 RTP/AVP 97
c=IN IP4 10.6.12.182
b=TIAS:320000
a=label:11
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;max-fs=3601;level-asymmetry-allowed=1
a=content:main
m=video 32352 RTP/AVP 97
c=IN IP4 10.6.12.182
b=TIAS:320000
a=label:12
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;max-fs=3601;level-asymmetry-allowed=1
a=content:slides
m=application 5070 UDP/BFCP *
c=IN IP4 10.6.12.182
a=floorctrl:s-only
a=floorid:2 mstrm:12
a=confid:22
a=userid:22
m=application 0 RTP/AVP 96
c=IN IP4 10.6.99.71
a=rtpmap:96 H224/0
|
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(856)" Method="IIWFTarget::sendSipRequest" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" tranId="4294967295" rRequest="ACK sip:4610003 at 10.6.99.71:5060 SIP/2.0
Via: SIP/2.0/TCP 10.6.99.2:5060;branch=z9hG4bKa91ff7b49a6b9;received=10.6.99.2;ingress-zone=DefaultZone
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 101 ACK
From: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
To: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
Max-Forwards: 70
Date: Wed, 07 May 2014 18:12:15 GMT
Allow-Events: presence
X-TAATag: 38e10662-d612-11e3-8836-0010f3230a42
Content-Type: application/sdp
Content-Length: 844
v=0
o=CiscoSystemsCCM-SIP 893499 1 IN IP4 10.6.99.2
s=SIP Call
b=AS:384
t=0 0
m=audio 18444 RTP/AVP 0 101
c=IN IP4 10.6.12.182
b=TIAS:64000
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 26574 RTP/AVP 97
c=IN IP4 10.6.12.182
b=TIAS:320000
a=label:11
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;max-fs=3601;level-asymmetry-allowed=1
a=content:main
m=video 32352 RTP/AVP 97
c=IN IP4 10.6.12.182
b=TIAS:320000
a=label:12
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;max-fs=3601;level-asymmetry-allowed=1
a=content:slides
m=application 5070 UDP/BFCP *
c=IN IP4 10.6.12.182
a=floorctrl:s-only
a=floorid:2 mstrm:12
a=confid:22
a=userid:22
m=application 0 RTP/AVP 96
c=IN IP4 10.6.99.71
a=rtpmap:96 H224/0
"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingAckWithSdpAnswerState.cpp(18)" Method="IWFAwaitingAckWithSdpAnswerState::sipAckRequest" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(89)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Timer = TIMER_SIP_TIMER_B
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(89)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Timer = TIMER_SIP_200OK_RETRANSMIT
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf.offeranswer" Level="DEBUG" CodeLocation="ppcmains/sip/offeranswer/OfferAnswerImpl.cpp(204)" Method="OfferAnswerImpl::processAnswer" Thread="0x7fd4e3c94700": Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" answerMediaLines="
connectionAddress: ['UNDEF''UNDEF''Error As String']
bandwidthUniBps: 384000
sessionVersion: 1
ice lite: false
activeLines: 5
medialine index: 0
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_AUDIO
szType: audio
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels:
answer modes:
label:
connAddr: ['IPv4''UDP''10.6.12.182:18444']
rtcpAddr['IPv4''UDP''10.6.12.182:18445']
tias: 64000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 2
0 = 0: G.711Mu_64 [ 0] clockRate: 8000 maxMsec:40
1 = 0: DTMF [101] clockRate: 8000 maxMsec:40 first:0 last:15
medialine index: 1
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_VIDEO
szType: video
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels: CONTENT_MAIN
answer modes:
label: 11
connAddr: ['IPv4''UDP''10.6.12.182:26574']
rtcpAddr['IPv4''UDP''10.6.12.182:26575']
tias: 320000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 1
0 = 0: H.264 [ 97] clockRate:90000 @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
medialine index: 2
rejected: false
mediaClass: SDP_MF_AU_VID
eType: SDP_MEDIA_TYPE_VIDEO
szType: video
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels: CONTENT_SLIDES
answer modes:
label: 12
connAddr: ['IPv4''UDP''10.6.12.182:32352']
rtcpAddr['IPv4''UDP''10.6.12.182:32353']
tias: 320000
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 1
0 = 0: H.264 [ 97] clockRate:90000 @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
medialine index: 3
rejected: false
mediaClass: SDP_MF_BFCP
eType: SDP_MEDIA_TYPE_APPLICATION
szType: application
trans: SDP_MEDIA_TRANS_UDP_BFCP
szTransport: UDP/BFCP
direction: SDP_MEDIA_DIR_UNDEF
content labels:
answer modes:
label:
connAddr: ['IPv4''UDP''10.6.12.182:5070']
rtcpAddr['IPv4''UDP''10.6.12.182:5071']
eFloorCtrRole: FLOORCTRLROLE_S_ONLY eSetupRole: SETUPROLE_UNDEF eConnVal: CONNVAL_UNDEF iConferenceID: 22 iUserID: 22 iFloorID: 2 iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 0
medialine index: 4
rejected: true
mediaClass: SDP_MF_H224
eType: SDP_MEDIA_TYPE_APPLICATION
szType: application
trans: SDP_MEDIA_TRANS_RTP_AVP
szTransport: RTP/AVP
direction: SDP_MEDIA_DIR_SENDRECV
content labels:
answer modes:
label:
connAddr: ['IPv4''UDP''10.6.99.71:0']
rtcpAddr['IPv4''UDP''10.6.99.71:1']
iceMediaStream: Number of Candidates: 0
cryptoParams:
rtcpFbSdpAttributeList:
rtcpAttributePresent: false
iceMismatchAttributePresent: false
Caps: 1
0 = 0: H.224 [ 96]
"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,144" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipData.cpp(232)" Method="IWFSipData::changeOfferAnswerState" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" change OAState from IWFIncomingDelayedOffer to IWFOfferAnswerIdleState
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(138)" Method="IWFCapsManager::setSIPCapSet" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" CapSet Before storing: Capset contains 5 elements
1: G.711Mu_64 [ 0] clockRate: 8000 maxMsec:40
2: DTMF [101] clockRate: 8000 maxMsec:40 first:0 last:15
3: H.264 [ 97] clockRate:90000 @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
4: H.264 [ 97] clockRate:90000 Duo-Presentation @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
5: H.239 Control
{
0 : { 0:[ 1 2 ] 1:[ 3 ] }
1 : { 0:[ 1 2 ] }
2 : { 0:[ 1 2 ] 1:[ 3 ] 2:[ 4 ] }
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(280)" Method="IWFCapsManager::setSIPCapState" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" SIP capset changed from: Capset contains 0 elements
{
} to: Capset contains 5 elements
1: G.711Mu_64 [ 0] clockRate: 8000 maxMsec:40
3: H.264 [ 97] clockRate:90000 @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
4: H.264 [ 97] clockRate:90000 Duo-Presentation @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
5: H.239 Control
6: DTMF
{
0 : { 0:[ 1 ] 1:[ 3 ] }
1 : { 0:[ 1 ] }
2 : { 0:[ 1 ] 1:[ 3 ] 2:[ 4 ] }
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(153)" Method="IWFCapsManager::setSIPCapSet" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Stored capsset: Capset contains 5 elements
1: G.711Mu_64 [ 0] clockRate: 8000 maxMsec:40
3: H.264 [ 97] clockRate:90000 @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
4: H.264 [ 97] clockRate:90000 Duo-Presentation @ 384.0kbit/s, iProfile:66 iPacketizationMode:0 iConstraintSet:224 defaultLevel:3.1 maxBR:168000 maxMBPS:216 maxFS:3601 maxDPB:0 maxCPB:0 maxStaticMBPS:216
5: H.239 Control
6: DTMF
{
0 : { 0:[ 1 ] 1:[ 3 ] }
1 : { 0:[ 1 ] }
2 : { 0:[ 1 ] 1:[ 3 ] 2:[ 4 ] }
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(183)" Method="IWFCapsManager::sipSideHasDuoCapabilites" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" returning: true
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(988)" Method="Interworking::setBfcpRole" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" BFCP Role = FLOORCTRLROLE_C_ONLY
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(183)" Method="IWFCapsManager::sipSideHasDuoCapabilites" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" returning: true
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFCapsManager.cpp(116)" Method="IWFCapsManager::h323SideHasDuoCapabilites" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" returning: true
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(997)" Method="Interworking::setBfcpState" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" BFCP Role = FLOORCTRLROLE_C_ONLY
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(89)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Timer = TIMER_BFCP_HELLO
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(49)" Method="IWFBandwidthMgr::setSipBandwidthUniKbps" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" h323:2048kbps sip:384kbps default:2048kbps graph:2048kbps
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFBandwidthMgr.cpp(102)" Method="IWFBandwidthMgr::updateBandwidthGraph" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" No need to change call bandwith, remaining at 2048kbps
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(824)" Method="Interworking::extractInfoFromInviteOrAck" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" SDP found in sip message.
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(240)" Method="Interworking::conditionallyAddHintMCUToRemoteContactAlias" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFEncryptionMgr.cpp(64)" Method="IWFEncryptionMgr::canAddEncryptionToTCS" Thread="0x7fd4e3c94700": canAddEncryptionToTCS="false" m_h323SetupConnectSupportsAES="false" m_sipCapsSupportsAES="false" m_sipSupportsTLS="false" m_zoneProfileSupportsEncryption="true" configurationAllowsEncryption()="true"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,145" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(259)" Method="Interworking::sendH245TCS" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(244)" Method="IIWFTarget::sendH245MsgToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Sending H245Msg =
value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : { sequenceNumber 0, protocolIdentifier { 0 0 8 245 0 13 }, multiplexCapability h2250Capability : { maximumAudioDelayJitter 60, receiveMultipointCapability { multicastCapability FALSE, multiUniCastConference FALSE, mediaDistributionCapability { { centralizedControl FALSE, distributedControl FALSE, centralizedAudio FALSE, distributedAudio FALSE, centralizedVideo FALSE, distributedVideo FALSE } } }, transmitMultipointCapability { multicastCapability FALSE, multiUniCastConference FALSE, mediaDistributionCapability { { centralizedControl FALSE, distributedControl FALSE, centralizedAudio FALSE, distributedAudio FALSE, centralizedVideo FALSE, distributedVideo FALSE } } }, receiveAndTransmitMultipointCapability { multicastCapability FALSE, multiUniCastConference FALSE, mediaDistributionCapability { { centralizedControl FALSE, distributedControl FALSE, centralizedAudio FALSE, distributedAudio FALSE, centralizedVideo FALSE, distributedVideo FALSE } } }, mcCapability { centralizedConferenceMC FALSE, decentralizedConferenceMC FALSE }, rtcpVideoControlCapability FALSE, mediaPacketizationCapability { h261aVideoPacketization FALSE, rtpPayloadType { { payloadDescriptor oid : { 0 0 8 241 0 0 0 0 } } } }, transportCapability { qOSCapabilities { { rsvpParameters { qosMode guaranteedQOS : NULL } } }, mediaChannelCapabilities { { mediaTransport ip-UDP : NULL } } }, logicalChannelSwitchingCapability FALSE, t120DynamicPortCapability FALSE }, capabilityTable { { capabilityTableEntryNumber 1, capability receiveAudioCapability : g711Ulaw64k : 40 }, { capabilityTableEntryNumber 3, capability receiveVideoCapability : genericVideoCapability : { capabilityIdentifier standard : { 0 0 8 241 0 0 1 }, maxBitRate 3840, collapsing { { parameterIdentifier standard : 41, parameterValue booleanArray : 64 }, { parameterIdentifier standard : 42, parameterValue unsignedMin : 71 }, { parameterIdentifier standard : 4, parameterValue unsignedMin : 15 } } } }, { capabilityTableEntryNumber 4, capability receiveVideoCapability : extendedVideoCapability : { videoCapability { genericVideoCapability : { capabilityIdentifier standard : { 0 0 8 241 0 0 1 }, maxBitRate 3840, collapsing { { parameterIdentifier standard : 41, parameterValue booleanArray : 64 }, { parameterIdentifier standard : 42, parameterValue unsignedMin : 71 }, { parameterIdentifier standard : 4, parameterValue unsignedMin : 15 } } } }, videoCapabilityExtension { { capabilityIdentifier standard : { 0 0 8 239 1 2 }, collapsing { { parameterIdentifier standard : 1, parameterValue booleanArray : 1 } } } } } }, { capabilityTableEntryNumber 5, capability genericControlCapability : { capabilityIdentifier standard : { 0 0 8 239 1 1 } } }, { capabilityTableEntryNumber 6, capability receiveAndTransmitUserInputCapability : dtmf : NULL } }, capabilityDescriptors { { capabilityDescriptorNumber 0, simultaneousCapabilities { { 1 }, { 3 } } }, { capabilityDescriptorNumber 1, simultaneousCapabilities { { 1 } } }, { capabilityDescriptorNumber 2, simultaneousCapabilities { { 1 }, { 3 }, { 4 } } } } }
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(708)" Method="SIPToH323Target::getVendorBlock" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingAckWithSdpAnswerState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFAwaitingAckWithSdpAnswerState to IWFAwaitingMSDCompleteState. Buffered state = IWFSipConnectedConnectingH323State
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(294)" Method="Interworking::sendH245MasterSlaveRequest" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(244)" Method="IIWFTarget::sendH245MsgToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Sending H245Msg =
value MultimediaSystemControlMessage ::= request : masterSlaveDetermination : { terminalType 60, statusDeterminationNumber 7616271 }
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,151" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMSDState.cpp(77)" Method="IWFMSDState::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" old state="IWFMSDIdleState" new state="IWFMSDOutgoingState"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.mediarouting" Level="DEBUG": Detail="Route: Storing at index 2: Packets Rxd on 10.6.99.71:0 (socket 0x5947e50) from UNDEFINED Txd on 10.6.99.71:50116 (socket 0x59f1ed0) to 10.6.12.182:5070 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.mediarouting" Level="DEBUG": Detail="Route: Storing at index 3: Packets Rxd on 10.6.99.71:0 (socket 0x5957f60) from UNDEFINED Txd on 10.6.99.71:50117 (socket 0x5a02100) to 10.6.12.182:5071 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="30085"
Detail="Sending H.245 TerminalCapabilitySetRequest "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="30085"
Sending H.245 PDU:
value MultimediaSystemControlMessage ::= request : terminalCapabilitySet :
{
sequenceNumber 0,
protocolIdentifier { 0 0 8 245 0 13 },
multiplexCapability h2250Capability :
{
maximumAudioDelayJitter 60,
receiveMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl FALSE,
distributedControl FALSE,
centralizedAudio FALSE,
distributedAudio FALSE,
centralizedVideo FALSE,
distributedVideo FALSE
}
}
},
transmitMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl FALSE,
distributedControl FALSE,
centralizedAudio FALSE,
distributedAudio FALSE,
centralizedVideo FALSE,
distributedVideo FALSE
}
}
},
receiveAndTransmitMultipointCapability
{
multicastCapability FALSE,
multiUniCastConference FALSE,
mediaDistributionCapability
{
{
centralizedControl FALSE,
distributedControl FALSE,
centralizedAudio FALSE,
distributedAudio FALSE,
centralizedVideo FALSE,
distributedVideo FALSE
}
}
},
mcCapability
{
centralizedConferenceMC FALSE,
decentralizedConferenceMC FALSE
},
rtcpVideoControlCapability FALSE,
mediaPacketizationCapability
{
h261aVideoPacketization FALSE,
rtpPayloadType
{
{
payloadDescriptor oid : { 0 0 8 241 0 0 0 0 }
}
}
},
transportCapability
{
qOSCapabilities
{
{
rsvpParameters
{
qosMode guaranteedQOS : NULL
}
}
},
mediaChannelCapabilities
{
{
mediaTransport ip-UDP : NULL
}
}
},
logicalChannelSwitchingCapability FALSE,
t120DynamicPortCapability FALSE
},
capabilityTable
{
{
capabilityTableEntryNumber 1,
capability receiveAudioCapability : g711Ulaw64k : 40
},
{
capabilityTableEntryNumber 3,
capability receiveVideoCapability : genericVideoCapability :
{
capabilityIdentifier standard : { 0 0 8 241 0 0 1 },
maxBitRate 3840,
collapsing
{
{
parameterIdentifier standard : 41,
parameterValue booleanArray : 64
},
{
parameterIdentifier standard : 42,
parameterValue unsignedMin : 71
},
{
parameterIdentifier standard : 4,
parameterValue unsignedMin : 15
}
}
}
},
{
capabilityTableEntryNumber 4,
capability receiveVideoCapability : extendedVideoCapability :
{
videoCapability
{
genericVideoCapability :
{
capabilityIdentifier standard : { 0 0 8 241 0 0 1 },
maxBitRate 3840,
collapsing
{
{
parameterIdentifier standard : 41,
parameterValue booleanArray : 64
},
{
parameterIdentifier standard : 42,
parameterValue unsignedMin : 71
},
{
parameterIdentifier standard : 4,
parameterValue unsignedMin : 15
}
}
}
},
videoCapabilityExtension
{
{
capabilityIdentifier standard : { 0 0 8 239 1 2 },
collapsing
{
{
parameterIdentifier standard : 1,
parameterValue booleanArray : 1
}
}
}
}
}
},
{
capabilityTableEntryNumber 5,
capability genericControlCapability :
{
capabilityIdentifier standard : { 0 0 8 239 1 1 }
}
},
{
capabilityTableEntryNumber 6,
capability receiveAndTransmitUserInputCapability : dtmf : NULL
}
},
capabilityDescriptors
{
{
capabilityDescriptorNumber 0,
simultaneousCapabilities
{
{
1
},
{
3
}
}
},
{
capabilityDescriptorNumber 1,
simultaneousCapabilities
{
{
1
}
}
},
{
capabilityDescriptorNumber 2,
simultaneousCapabilities
{
{
1
},
{
3
},
{
4
}
}
}
}
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(708)" Method="SIPToH323Target::getVendorBlock" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="30085"
Detail="Sending H.245 MasterSlaveDeterminationRequest "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,152" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="30085"
Sending H.245 PDU:
value MultimediaSystemControlMessage ::= request : masterSlaveDetermination :
{
terminalType 60,
statusDeterminationNumber 7616271
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,153" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,154" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001159" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,162" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,163" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.000955" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="30085"
Detail="Received H.245 MasterSlaveDeterminationRequest "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="30085"
Received H.245 PDU:
value MultimediaSystemControlMessage ::= request : masterSlaveDetermination :
{
terminalType 240,
statusDeterminationNumber 12423161
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMSDOutgoingState.cpp(48)" Method="IWFMSDOutgoingState::h245MasterSlaveRequest" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(89)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Timer = TIMER_H323_MSD_T106
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFH323Funcs.cpp(309)" Method="Interworking::sendH245MasterSlaveAck" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(244)" Method="IIWFTarget::sendH245MsgToLeg" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Sending H245Msg =
value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : { decision master : NULL }
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(708)" Method="SIPToH323Target::getVendorBlock" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFMSDState.cpp(77)" Method="IWFMSDState::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" old state="IWFMSDOutgoingState" new state="IWFMSDIncomingState"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="INFO": Dst-ip="10.160.0.140" Dst-port="30085"
Detail="Sending H.245 MasterSlaveDeterminationAckResponse Master"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="DEBUG": Dst-ip="10.160.0.140" Dst-port="30085"
Sending H.245 PDU:
value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck :
{
decision master : NULL
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="1720"
Detail="Received H.225 ReleaseComplete Q.931 Cause:No route to destination, H.225 Cause:Unreachable destination, Additional Info:None"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="1720"
Received H.225 PDU:
Q931
{
Message Type: Release Complete
Call reference flag: Message sent to originating side
Call reference value: 0xa
Info Element : Cause
{
Location: Usr
Cause Value: No route to destination
}
Info Element : Display
{
Display Information = Huawei Technologies Co., Ltd.
}
Info Element : User User
{
Length = 23
}
}
value H323-UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 6 },
reason unreachableDestination : NULL,
callIdentifier
{
guid '38E2C718D61211E3AB430010F3230A42'H
}
},
h245Tunneling FALSE
}
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,326" Module="developer.iwf" Level="ERROR" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(576)" Method="IWFState::h225ReleaseComplete" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" : Invalid State/Event Combination. last states="IWFAwaitingConnectH323OutLegState IWFAwaitingTCSState IWFAwaitingAckWithSdpAnswerState IWFAwaitingMSDCompleteState "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15018" Dst-ip="10.160.0.140" Dst-port="1720" Detail="TCP Connection Closed"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="30085"
Detail="Received H.245 TerminalCapabilitySetRejResponse "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="30085"
Received H.245 PDU:
value MultimediaSystemControlMessage ::= response : terminalCapabilitySetReject :
{
sequenceNumber 0,
cause unspecified : NULL
}
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="ERROR" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaitingMSDCompleteState.cpp(33)" Method="IWFAwaitingMSDCompleteState::h245TCSRejResponse" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" last states="IWFAwaitingConnectH323OutLegState IWFAwaitingTCSState IWFAwaitingAckWithSdpAnswerState IWFAwaitingMSDCompleteState "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="ERROR" CodeLocation="ppcmains/oak/calls/iwf/IWFBufferedStateProxy.cpp(59)" Method="IWFBufferedStateProxy::terminateCallOnError" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" reason: Request Terminated last states="IWFAwaitingConnectH323OutLegState IWFAwaitingTCSState IWFAwaitingAckWithSdpAnswerState IWFAwaitingMSDCompleteState "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(100)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Stopping all timers
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFAwaitingMSDCompleteState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFAwaitingMSDCompleteState to IWFSipConnectedConnectingH323State. Buffered state = IWFNullState
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="ERROR" CodeLocation="ppcmains/oak/calls/iwf/IWFConnectedStateBase.cpp(198)" Method="IWFConnectedStateBase::terminateCallOnError" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" reason:Request Terminated last states="IWFAwaitingConnectH323OutLegState IWFAwaitingTCSState IWFAwaitingAckWithSdpAnswerState IWFAwaitingMSDCompleteState IWFSipConnectedConnectingH323State "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFTimer.cpp(100)" Method="IWFTimer::timerStop" Thread="0x7fd4e3c94700": Stopping all timers
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFSipFuncs.cpp(561)" Method="Interworking::sendSipBye" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(327)" Method="IIWFTarget::sendSipRequestToLeg" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" rRequest="BYE sip:2972 at 10.6.99.2:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK5c0643c5aa9ebcffb5ff2189c9f9feea534
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 1 BYE
From: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
To: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
Max-Forwards: 70
Route: <sip:proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42 at 10.6.99.71:5060;transport=tcp;lr>
User-Agent: TANDBERG/4120 (X7.2)
P-Asserted-Identity: <sip:4610003 at 10.6.99.71>
Content-Length: 0
"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFSipConnectedConnectingH323State" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFSipConnectedConnectingH323State to IWFAwaiting200OKForByeState. Buffered state = IWFNullState
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,327" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.sip" Level="INFO": Dst-ip="10.6.99.2" Dst-port="5060" Detail="Sending Request Method=BYE, Request-URI=sip:2972 at 10.6.99.2:5060;transport=tcp, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.sip" Level="DEBUG": Dst-ip="10.6.99.2" Dst-port="5060"
SIPMSG:
|BYE sip:2972 at 10.6.99.2:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 10.6.99.71:5060;egress-zone=DefaultZone;branch=z9hG4bK2e9a6ae7dd0c500aa06c2e7c4e2cc124535;proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42;rport
Via: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK5c0643c5aa9ebcffb5ff2189c9f9feea534
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 1 BYE
From: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
To: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
Max-Forwards: 69
User-Agent: TANDBERG/4120 (X7.2)
P-Asserted-Identity: <sip:4610003 at 10.6.99.71>
Content-Length: 0
|
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(810)" Method="IIWFTarget::disconnectH225" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaiting200OKForByeState.cpp(64)" Method="IWFAwaiting200OKForByeState::h225Disconnect" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Ignored
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="30085"
Detail="Received H.245 EndSessionCommand "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="30085"
Received H.245 PDU:
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaiting200OKForByeState.cpp(50)" Method="IWFAwaiting200OKForByeState::h245EndSessionCommand" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Ignored
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.h323" Level="INFO": Src-ip="10.160.0.140" Src-port="30085"
Detail="Received H.245 EndSessionCommand "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.h323" Level="DEBUG": Src-ip="10.160.0.140" Src-port="30085"
Received H.245 PDU:
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaiting200OKForByeState.cpp(50)" Method="IWFAwaiting200OKForByeState::h245EndSessionCommand" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Ignored
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="network.tcp" Level="DEBUG": Src-ip="10.6.99.71" Src-port="15019" Dst-ip="10.160.0.140" Dst-port="30085" Detail="TCP Connection Closed"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(832)" Method="IIWFTarget::disconnectH245" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(667)" Method="IWFState::h245Disconnect" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" : Ignored
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFAwaiting200OKForByeState.cpp(71)" Method="IWFAwaiting200OKForByeState::targetLegDissociateFromCall" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(152)" Method="IWFState::stateExit" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(421)" Method="IIWFTarget::changeState" Thread="0x7fd4e3c94700": State="IWFAwaiting200OKForByeState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" From IWFAwaiting200OKForByeState to IWFNullState. Buffered state = IWFNullState
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(145)" Method="IWFState::stateEntry" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,328" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(494)" Method="IIWFTarget::dissociateIwfLegFromCall" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="network.sip" Level="INFO": Src-ip="10.6.99.2" Src-port="5060" Detail="Receive Response Code=200, Method=BYE, To=sip:2972 at 10.6.99.2, Call-ID=1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="network.sip" Level="DEBUG": Src-ip="10.6.99.2" Src-port="5060"
SIPMSG:
|SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.6.99.71:5060;egress-zone=DefaultZone;branch=z9hG4bK2e9a6ae7dd0c500aa06c2e7c4e2cc124535;proxy-call-id=38e104be-d612-11e3-bf3a-0010f3230a42;rport,SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK5c0643c5aa9ebcffb5ff2189c9f9feea534
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 1 BYE
From: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
To: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
Date: Wed, 07 May 2014 18:12:16 GMT
Content-Length: 0
|
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(899)" Method="IIWFTarget::sendSipResponse" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Response =
SIP/2.0 200 OK
Via: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK5c0643c5aa9ebcffb5ff2189c9f9feea534
Call-ID: 1cf16f00-36a1777f-a8f18-263060a at 10.6.99.2
CSeq: 1 BYE
From: <sip:4610003 at 10.6.99.71>;tag=4b55f555238fe319
To: "NTI / Atendimento (Jabber)" <sip:2972 at 10.6.99.2>;tag=893499~c7bd2117-4182-4ada-aa1a-69faab1e67bb-33568489
Date: Wed, 07 May 2014 18:12:16 GMT
Content-Length: 0
Trans Id = 4294967295
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(1535)" Method="IWFState::handleSip2xxMsg" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" :
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IWFState.cpp(266)" Method="IWFState::sip200OKResponse" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" : Ignored
2014-05-07T15:05:51-03:00 vcsc tvcs: Event="Call Disconnected" Service="SIP" Src-ip="10.6.99.2" Src-port="5060" Src-alias-type="SIP" Src-alias="sip:2972 at 10.6.99.2" Dst-alias-type="SIP" Dst-alias="sip:4610003 at 10.6.99.71:5060" Call-serial-number="38e104be-d612-11e3-bf3a-0010f3230a42" Tag="38e10662-d612-11e3-8836-0010f3230a42" Protocol="TCP" Level="1" UTCTime="2014-05-07 18:05:51,330"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(630)" Method="SIPToH323Target::aboutToMoveToHistory" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/SIPToH323Target.cpp(130)" Method="SIPToH323Target::~SIPToH323Target" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42" Destructor
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,330" Module="developer.iwf" Level="DEBUG" CodeLocation="ppcmains/oak/calls/iwf/IIWFTarget.cpp(685)" Method="IIWFTarget::destroyTarget" Thread="0x7fd4e3c94700": State="IWFNullState" Global-CallId="38e10662-d612-11e3-8836-0010f3230a42" Local-CallId="38e104be-d612-11e3-bf3a-0010f3230a42"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,331" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:9999/licensemanager/free" Ref="0x7fd4dc2245b0"
2014-05-07T15:05:51-03:00 vcsc UTCTime="2014-05-07 18:05:51,332" Module="developer.licensemanager.service.manager" Level="INFO" CodeLocation="licensemanager(192)" Detail="License freed" call_id="38e1059a-d612-11e3-8209-0010f3230a42" lic_type="traversal"
2014-05-07T15:05:51-03:00 vcsc licensemanager: Level="INFO" Detail="License freed" call_id="38e1059a-d612-11e3-8209-0010f3230a42" lic_type="traversal" UTCTime="2014-05-07 18:05:51,332"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Route: Deleting at index 2: Packets Rxd on 10.6.99.71:0 (socket 0x5947e50) from UNDEFINED Txd on 10.6.99.71:50116 (socket 0x59f1ed0) to 10.6.12.182:5070 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Route: Deleting at index 3: Packets Rxd on 10.6.99.71:0 (socket 0x5957f60) from UNDEFINED Txd on 10.6.99.71:50117 (socket 0x5a02100) to 10.6.12.182:5071 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Route: Deleting at index 0: Packets Rxd on 10.6.99.71:50116 (socket 0x59f1ed0) from UNDEFINED Txd on 10.6.99.71:0 (socket 0x5947e50) to 127.0.0.1:0 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Route: Deleting at index 1: Packets Rxd on 10.6.99.71:50117 (socket 0x5a02100) from UNDEFINED Txd on 10.6.99.71:0 (socket 0x5957f60) to 127.0.0.1:0 "
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,333" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.mediarouting" Level="DEBUG": Detail="Received SocketPairFreeReq Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.mediarouting" Level="DEBUG": Detail="Sending FreeCnf Msg"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="9999" Dst-ip="127.0.0.1" Dst-port="43192" Response="200 OK" ResponseTime="0.002256" Ref="0x7fd4dc2245b0"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,334" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:4370/status/call/call/uuid/38e104be-d612-11e3-bf3a-0010f3230a42" Ref="0x5493d30"
2014-05-07T15:05:51-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:51,336" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="4370" Dst-ip="127.0.0.1" Dst-port="41370" Response="200 OK" ResponseTime="0.001397" Ref="0x5493d30"
2014-05-07T15:05:53-03:00 vcsc tvcs: UTCTime="2014-05-07 18:05:53,901" Module="network.h323" Level="INFO": Dst-ip="189.90.56.134" Dst-port="6004"
Detail="Sending RAS GRQ SeqNum=39434 Retransmit=True"
2014-05-07T15:05:55-03:00 vcsc httpd[13223]: web: Event="Diagnostic Logging" Detail="Logging stopped; DEBUG_MARKER:" UTCTime="2014-05-07 18:05:55"
2014-05-07T15:05:55-03:00 vcsc httpd[13223]: web: Event="System Configuration Changed" Detail="configuration/loggingsnapshot/end_time - changed from: '0' to: '1399485955'" Src-ip="10.6.12.45" Src-port="61163" User="admin" Level="1" UTCTime="2014-05-07 18:05:55"
2014-05-07T15:05:55-03:00 vcsc httpd[13223]: web: Event="System Configuration Changed" Detail="configuration/loggingsnapshot/saved_log_levels - changed from: '{'network':'INFO','developer.iwf':'INFO'}' to: '{}'" Src-ip="10.6.12.45" Src-port="61163" User="admin" Level="1" UTCTime="2014-05-07 18:05:55"
2014-05-07T15:05:55-03:00 vcsc httpd[13223]: web: Event="System Configuration Changed" Detail="configuration/networklogger/uuid/473b1564-643f-11e1-beb2-0010f3230a42/level - changed from: 'DEBUG' to: 'INFO'" Src-ip="10.6.12.45" Src-port="61163" User="admin" Level="1" UTCTime="2014-05-07 18:05:55"
stop log
More information about the cisco-voip
mailing list