Created
June 4, 2011 07:05
-
-
Save zlu/1007688 to your computer and use it in GitHub Desktop.
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| 2011-06-04 00:04:58.129 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: Creating xmpp session with app: ApplicationImpl[_symbolicName=tropo2-0, _state=STARTED] #[N/A][N/A][N/A][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.133 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: Created session: XMPPSessionImpl: _id=xmpps_15q43a2hbj6s4, _direction=RECEIVEStream, _sessionType=CLIENT, _remotJID=null, _state=INITIAL, _remoteAddr=/127.0.0.1, _remotePort=53822, _localPort=5222, _localJID=127.0.0.1, _application=ApplicationImpl[_symbolicName=tropo2-0, _state=STARTED] #[N/A][N/A][N/A][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.134 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" to="127.0.0.1" version="1.0" xml:lang="en"> #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.136 DEBUG [XMPPService-t-40] #XMPP#: (o)<stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" xmlns:db="jabber:server:dialback" version="1.0" from="127.0.0.1" id="xmpps_15q43a2hbj6s4" xml:lang="en"> #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.138 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.139 DEBUG [XMPPService-t-40] #XMPP#: (o)<stream:features><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>PLAIN</mechanism></mechanisms></stream:features> #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.139 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.142 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">dXNlcmFAMTI3LjAuMC4xAHVzZXJhADE=</auth> #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.148 DEBUG [XMPPService-t-40] #XMPP#: (o)<success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/> #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.148 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][?, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.152 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" to="127.0.0.1" version="1.0" xml:lang="en"> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.152 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:04:58.153 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:04:58.153 DEBUG [XMPPService-t-40] #XMPP#: (o)<stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" xmlns:db="jabber:server:dialback" version="1.0" from="127.0.0.1" id="xmpps_15q43a2hbj6s4" xml:lang="en"> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.153 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.154 DEBUG [XMPPService-t-40] #XMPP#: (o)<stream:features><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></stream:features> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.155 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.157 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<iq type="set" id="blather0002"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.157 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:04:58.157 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected], 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:04:58.159 DEBUG [XMPPService-t-40] <iq type="set" id="blather0002" to="127.0.0.1" from="[email protected]"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/></iq> :: xmpps_15q43a2hbj6s4 | |
| 2011-06-04 00:04:58.160 DEBUG [XMPPService-t-40] #XMPP#: Intercept iq bind result, set full jid [email protected]/voxeo to session XMPPSessionImpl: _id=xmpps_15q43a2hbj6s4, _direction=RECEIVEStream, _sessionType=CLIENT, [email protected]/voxeo, _state=CONFIRMED, _remoteAddr=/127.0.0.1, _remotePort=53822, _localPort=5222, _localJID=127.0.0.1, _application=ApplicationImpl[_symbolicName=tropo2-0, _state=STARTED] #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.160 DEBUG [XMPPService-t-40] #XMPP#: (o)<iq type="result" id="blather0002" from="127.0.0.1" to="[email protected]"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>[email protected]/voxeo</jid></bind></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.160 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.160 INFO [XMPPService-t-40] Bound client resource [[email protected]/voxeo] | |
| 2011-06-04 00:04:58.162 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<iq type="set" id="blather0004" to="127.0.0.1"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.163 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:04:58.163 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:04:58.163 DEBUG [XMPPService-t-40] <iq type="set" id="blather0004" to="127.0.0.1" from="[email protected]/voxeo"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> :: xmpps_15q43a2hbj6s4 | |
| 2011-06-04 00:04:58.163 DEBUG [XMPPService-t-40] #XMPP#: (o)<iq type="result" id="blather0004" from="127.0.0.1" to="[email protected]/voxeo"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.163 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.166 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<iq type="get" id="blather0006"><query xmlns="jabber:iq:roster"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.166 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:04:58.166 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:04:58.166 DEBUG [XMPPService-t-40] <iq type="get" id="blather0006" to="127.0.0.1" from="[email protected]/voxeo"><query xmlns="jabber:iq:roster"/></iq> :: xmpps_15q43a2hbj6s4 | |
| 2011-06-04 00:04:58.167 DEBUG [XMPPService-t-40] #XMPP#: (o)<iq type="error" id="blather0006" to="[email protected]/voxeo" from="127.0.0.1"><query xmlns="jabber:iq:roster"/><error type="cancel"><feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.167 DEBUG [XMPPService-t-40] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.169 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<presence/> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:04:58.170 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:04:58.170 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:05:02.472 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: (i)[INVITE sip:[email protected]:5060 SIP/2.0\r\nContent-Type: application/sdp\r\nTo: <sip:[email protected]:5060>\r\nVia: SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO\r\nCSeq: 1 INVITE\r\nContent-Length: 426\r\nSupported: replaces\r\nCall-ID: YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.\r\nUser-Agent: Bria 3 release 3.2 stamp 61503\r\nFrom: <sip:[email protected]>;tag=ae339368\r\nMax-Forwards: 70\r\nContact: <sip:[email protected]:15978>\r\n\r\nv=0\r\no=- 1307171102463224 1 IN IP4 192.168.0.196\r\ns=CounterPath Bria 3.2\r\nc=IN IP4 192.168.0.196\r\nt=0 0\r\na=ice-ufrag:6868a9\r\na=ice-pwd:cc09c20fb53aae595c484e32aab34fb3\r\nm=audio 59486 RTP/AVP 0 8 18 101\r\na=rtpmap:18 G729/8000\r\na=fmtp:18 annexb=yes\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=candidate:1 1 UDP 659136 192.168.0.196 59486 typ host\r\na=candidate:1 2 UDP 659134 192.168.0.196 59487 typ host\r\n] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.473 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: The transaction was created: z9hG4bK-d8754z-5021101bdd47d118-1---d8754z- #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.474 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: (o)[SIP/2.0 100 Trying\r\nTo: <sip:[email protected]:5060>\r\nVia: SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport=15978\r\nCSeq: 1 INVITE\r\nContent-Length: 0\r\nCall-ID: YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.\r\nFrom: <sip:[email protected]>;tag=ae339368\r\n\r\n] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected]:5060(127.0.0.1:15978)][sip:[email protected](192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.474 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: Send message to: /127.0.0.1:15978 by udp #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected]:5060(127.0.0.1:15978)][sip:[email protected](192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.474 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: Connected to 127.0.0.1:15978/udp #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected]:5060(127.0.0.1:15978)][sip:[email protected](192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.476 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: Found [tropo2] to handle the request by alphabet. | |
| 2011-06-04 00:05:02.477 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: SipApplicationInfo: SipApplicationRouterInfo[ApplicationName=tropo2, RouteModifier=NO_ROUTE, RouteRegion=null, SubscriberURI=sip:[email protected]:5060, StateInfo=tropo2] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.478 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: Session creating: ss_1jr6l7qq24x4g #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.486 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: Session created:SipSessionImpl[_id=ss_1jr6l7qq24x4g, _parentId=sas_2-15-sm13ejiylruck25tropo2, _handler=Controller, _state=INITIAL, _fresh=false, _valid=true, _unavailable=false, _role=UNKNOW, _method=INVITE, _callId=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.487 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: SessionManagementLayer recv request: INVITE #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.487 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: ApplicationDispatchLayer invoke application: Controller with INVITE #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.487 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: SipServletWrapper[Controller, com.voxeo.moho.sip.SIPController] is forwarding. #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.488 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: ProcessSipSessionsInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:02.489 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: processInvalidationWhenReady [ss_1jr6l7qq24x4g], current State = INITIAL, Vaild:true, Role:UNKNOW, hasOngoingTransaction: false, OT: 0, ProcessingByApp: false, Fresh:false, invalidateWhenReady: true #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.489 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: processInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:02.489 DEBUG [MOHO-22] Received incoming call | |
| 2011-06-04 00:05:02.492 INFO [pool-16-thread-11] Message [SIPCallImpl[SipSessionAdaptor[sessionid=ss_1jr6l7qq24x4g, appSessionId=sas_2-15-sm13ejiylruck25tropo2],INVITING]] | |
| 2011-06-04 00:05:02.493 INFO [pool-16-thread-11] Incoming Call [SIPCallImpl[SipSessionAdaptor[sessionid=ss_1jr6l7qq24x4g, appSessionId=sas_2-15-sm13ejiylruck25tropo2],INVITING]] | |
| 2011-06-04 00:05:02.498 INFO [pool-16-thread-12] Message [SIPCallImpl[SipSessionAdaptor[sessionid=ss_1jr6l7qq24x4g, appSessionId=sas_2-15-sm13ejiylruck25tropo2],INVITING]] | |
| 2011-06-04 00:05:02.501 INFO [pool-16-thread-12] Event [OfferEvent[from=sip:[email protected],to=sip:[email protected]:5060,headers={Max-Forwards=70, Content-Length=426, Contact=<sip:[email protected]:15978>, Supported=replaces, Allow=INVITE, To=<sip:[email protected]:5060>, CSeq=1 INVITE, User-Agent=Bria 3 release 3.2 stamp 61503, Via=SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport=15978, Call-ID=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., Content-Type=application/sdp, From=<sip:[email protected]>;tag=ae339368}]] | |
| 2011-06-04 00:05:02.502 DEBUG [pool-16-thread-12] #XMPP#: (o)<iq type="set" from="[email protected]" to="[email protected]/voxeo" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900"><offer xmlns="urn:xmpp:ozone:1" to="sip:[email protected]:5060" from="sip:[email protected]"><header name="Max-Forwards" value="70"/><header name="Content-Length" value="426"/><header name="Contact" value="<sip:[email protected]:15978>"/><header name="Supported" value="replaces"/><header name="Allow" value="INVITE"/><header name="To" value="<sip:[email protected]:5060>"/><header name="CSeq" value="1 INVITE"/><header name="User-Agent" value="Bria 3 release 3.2 stamp 61503"/><header name="Via" value="SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport=15978"/><header name="Call-ID" value="YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg."/><header name="Content-Type" value="application/sdp"/><header name="From" value="<sip:[email protected]>;tag=ae339368"/></offer></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:02.503 DEBUG [pool-16-thread-12] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:02.507 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<iq type="result" to="[email protected]" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900" from="[email protected]/voxeo"/> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:02.509 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: ClientTransaction[State=TERMINATED, Key=xmpps_15q43a2hbj6s487ce9d77-a1ee-42d5-ba31-923e3b96b900] is released. #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:02.509 DEBUG [SocketAcceptorIoProcessor-0.2] #XMPP#: (i)<iq type="set" to="[email protected]" id="blather000b" from="[email protected]/voxeo"><answer xmlns="urn:xmpp:ozone:1"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:02.509 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:05:02.510 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Response #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:05:02.510 DEBUG [XMPPService-t-40] <iq type="result" to="[email protected]" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900" from="[email protected]/voxeo"/> :: xmpps_15q43a2hbj6s4 | |
| 2011-06-04 00:05:02.510 DEBUG [XMPPService-t-40] #XMPP#: Invoke application:tropo2 ozone | |
| 2011-06-04 00:05:02.510 DEBUG [XMPPService-t-40] #XMPP#: Forwarding Request #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][null] | |
| 2011-06-04 00:05:02.510 DEBUG [XMPPService-t-40] <iq type="set" to="[email protected]" id="blather000b" from="[email protected]/voxeo"><answer xmlns="urn:xmpp:ozone:1"/></iq> :: xmpps_15q43a2hbj6s4 | |
| 2011-06-04 00:05:02.511 INFO [pool-16-thread-12] Request [AnswerCommand[callId=86796ca8-5f96-44fb-9c35-4507e9d324e9,headers={}]] | |
| 2011-06-04 00:05:02.511 DEBUG [pool-16-thread-12] #SIP#: SessionManagementLayer send response: INVITE/180 #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.512 DEBUG [pool-16-thread-12] #SIP#: Add Path[ id=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.-ae339368-8286b5cien81, session=SipSessionImpl[_id=ss_1jr6l7qq24x4g, _parentId=sas_2-15-sm13ejiylruck25tropo2, _handler=Controller, _state=INITIAL, _fresh=false, _valid=true, _unavailable=false, _role=UAS, _method=INVITE, _callId=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.], party= CALLER] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.512 DEBUG [pool-16-thread-12] #SIP#: (o)[SIP/2.0 180 Ringing\r\nTo: <sip:[email protected]:5060>;tag=8286b5cien81\r\nVia: SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport=15978\r\nCSeq: 1 INVITE\r\nContent-Length: 0\r\nCall-ID: YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.\r\nFrom: <sip:[email protected]>;tag=ae339368\r\n\r\n] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.513 DEBUG [pool-16-thread-12] #SIP#: Send message to: /127.0.0.1:15978 by udp #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.513 DEBUG [pool-16-thread-12] #SIP#: Connected to 127.0.0.1:15978/udp #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.513 DEBUG [pool-16-thread-12] #SIP#: ProcessSipSessionsInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:02.514 DEBUG [pool-16-thread-12] #SIP#: processInvalidationWhenReady [ss_1jr6l7qq24x4g], current State = EARLY, Vaild:true, Role:UAS, hasOngoingTransaction: false, OT: 0, ProcessingByApp: false, Fresh:false, invalidateWhenReady: true #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:02.514 DEBUG [pool-16-thread-12] #SIP#: processInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:02.516 DEBUG [MOHO-23] Set ms id with call id :YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg. | |
| 2011-06-04 00:05:02.516 INFO [MOHO-23] #MSCTRL#: MS[MS-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] updates MEDIAOBJECT_ID, old:"ms2-7", new:"MS-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg." | |
| 2011-06-04 00:05:02.517 DEBUG [MOHO-23] Set nc id with call id :YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg. | |
| 2011-06-04 00:05:02.517 INFO [MOHO-23] #MSCTRL#: MS[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] updates MEDIAOBJECT_ID, old:"MS-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.", new:"NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg." | |
| 2011-06-04 00:05:02.517 INFO [MOHO-23] #MSCTRL#: MS[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] creates a MediaObject[BASIC, NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., {MEDIAOBJECT_ID=NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.}] | |
| 2011-06-04 00:05:02.517 INFO [MOHO-23] #MSCTRL#: SdpPort[IDLE, NC[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.]] processes a SDP offer \nv=0\r\no=- 1307171102463224 1 IN IP4 192.168.0.196\r\ns=CounterPath Bria 3.2\r\nc=IN IP4 192.168.0.196\r\nt=0 0\r\na=ice-ufrag:6868a9\r\na=ice-pwd:cc09c20fb53aae595c484e32aab34fb3\r\nm=audio 59486 RTP/AVP 0 8 18 101\r\na=rtpmap:18 G729/8000\r\na=fmtp:18 annexb=yes\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=candidate:1 1 UDP 659136 192.168.0.196 59486 typ host\r\na=candidate:1 2 UDP 659134 192.168.0.196 59487 typ host\r\n | |
| 2011-06-04 00:05:02.520 DEBUG [MOHO-23] #MSCTRL#: SdpPort[UNDER_PROCESSING, NC[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.]] moves state from [IDLE] to [UNDER_PROCESSING] | |
| 2011-06-04 00:05:02.520 DEBUG [MOHO-23] Start wait joinDelegate. CallID:YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg. | |
| 2011-06-04 00:05:02.520 INFO [msctrl/2-t-7] #MSCTRL#: No CodecPolicy is installed, just return back the initial offer | |
| 2011-06-04 00:05:02.521 DEBUG [msctrl/2-t-7] #MRCP#: load configuration from {maxThreadPool=2147483647, minThreadPool=25, MEDIAOBJECT_ID=NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., com.voxeo.useLocalConfigurationOnGUTFailures=false} | |
| 2011-06-04 00:05:07.648 ERROR [msctrl/2-t-7] #MRCP#: ERROR_CREATING_RTSP_CONNECTION@T: java.io.IOException: com.mot.mrcp.mrcpv1.rtsp.RtspConnection@1973d30 connect timeout after 5000\n at com.mot.mrcp.mrcpv1.rtsp.RtspConnection.<init>(RtspConnection.java:74)\n at com.mot.mrcp.mrcpv1.rtsp.RtspClient.newRtspConnection(RtspClient.java:53)\n at com.mot.mrcp.mrcpv1.client.MrcpClient.initAsrResource(MrcpClient.java:519)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpAsrSessionImpl.<init>(MrcpAsrSessionImpl.java:145)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpClientImpl.getAsrSession(MrcpClientImpl.java:106)\n at com.voxeo.mscontrol.networkconnection.MrcpNetworkConnection.initMRCPSession(MrcpNetworkConnection.java:104)\n at com.voxeo.mscontrol.networkconnection.MrcpSdpPortManager$CreateThread.run(MrcpSdpPortManager.java:245)\n at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)\n at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)\n at java.lang.Thread.run(Thread.java:680)\n | |
| 2011-06-04 00:05:07.649 ERROR [msctrl/2-t-7] #MSCTRL#: NC[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] failed to init MRCP Session with the user agent SDP:v=0\r\no=- 1307171102463224 1 IN IP4 192.168.0.196\r\ns=CounterPath Bria 3.2\r\nc=IN IP4 192.168.0.196\r\nt=0 0\r\na=ice-ufrag:6868a9\r\na=ice-pwd:cc09c20fb53aae595c484e32aab34fb3\r\nm=audio 59486 RTP/AVP 0 8 18 101\r\na=rtpmap:18 G729/8000\r\na=fmtp:18 annexb=yes\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=candidate:1 1 UDP 659136 192.168.0.196 59486 typ host\r\na=candidate:1 2 UDP 659134 192.168.0.196 59487 typ host\r\n@T: com.mot.mrcp.MrcpException: Error creating a RtspConnection\n at com.mot.mrcp.mrcpv1.rtsp.RtspClient.newRtspConnection(RtspClient.java:59)\n at com.mot.mrcp.mrcpv1.client.MrcpClient.initAsrResource(MrcpClient.java:519)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpAsrSessionImpl.<init>(MrcpAsrSessionImpl.java:145)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpClientImpl.getAsrSession(MrcpClientImpl.java:106)\n at com.voxeo.mscontrol.networkconnection.MrcpNetworkConnection.initMRCPSession(MrcpNetworkConnection.java:104)\n at com.voxeo.mscontrol.networkconnection.MrcpSdpPortManager$CreateThread.run(MrcpSdpPortManager.java:245)\n at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)\n at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)\n at java.lang.Thread.run(Thread.java:680)\n | |
| 2011-06-04 00:05:07.649 DEBUG [msctrl/2-t-7] #MSCTRL#: Return SdpPortEvt[SdpPort[UNDER_PROCESSING, NC[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.]], ANSWER_GENERATED, UNKNOWN_ERROR, Error creating a RtspConnection, NO_QUALIFIER, null, null] to the application | |
| 2011-06-04 00:05:07.651 DEBUG [msctrl/2-t-7] #SIP#: SessionManagementLayer send response: INVITE/500 #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.651 DEBUG [msctrl/2-t-7] #SIP#: (o)[SIP/2.0 500 Server Internal Error\r\nTo: <sip:[email protected]:5060>;tag=8286b5cien81\r\nVia: SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport=15978\r\nCSeq: 1 INVITE\r\nContent-Length: 0\r\nCall-ID: YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.\r\nFrom: <sip:[email protected]>;tag=ae339368\r\n\r\n] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.652 DEBUG [msctrl/2-t-7] #SIP#: Send message to: /127.0.0.1:15978 by udp #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.652 DEBUG [msctrl/2-t-7] #SIP#: Connected to 127.0.0.1:15978/udp #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.652 DEBUG [msctrl/2-t-7] #SIP#: ProcessSipSessionsInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:07.653 DEBUG [msctrl/2-t-7] #SIP#: processInvalidationWhenReady [ss_1jr6l7qq24x4g], current State = TERMINATED, Vaild:true, Role:UAS, hasOngoingTransaction: false, OT: 0, ProcessingByApp: false, Fresh:false, invalidateWhenReady: true #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.653 DEBUG [msctrl/2-t-7] #SIP#: server will automatically invalidate sipsession [ss_1jr6l7qq24x4g] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.654 DEBUG [msctrl/2-t-7] #SIP#: SipSessionImpl[_id=ss_1jr6l7qq24x4g, _parentId=sas_2-15-sm13ejiylruck25tropo2, _handler=Controller, _state=TERMINATED, _fresh=false, _valid=true, _unavailable=false, _role=UAS, _method=INVITE, _callId=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] is invalidating. (s) #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.655 DEBUG [msctrl/2-t-7] destroyNetworkConnection | |
| 2011-06-04 00:05:07.655 DEBUG [msctrl/2-t-7] #MSCTRL#: NC[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg., NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] moves state from [INITIALIZED] to [RELEASED] | |
| 2011-06-04 00:05:07.656 INFO [msctrl/2-t-7] #MSCTRL#: MS[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] removes a MediaObject[mscontrol://26.192.46.110:10074/NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg./NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] | |
| 2011-06-04 00:05:07.656 DEBUG [msctrl/2-t-7] #MSCTRL#: MS[NC-YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.] moves state from [INITIALIZED] to [RELEASED] | |
| 2011-06-04 00:05:07.657 DEBUG [msctrl/2-t-7] terminating call. Notifying joinDelegate conditaion. callID:YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg. | |
| 2011-06-04 00:05:07.658 DEBUG [msctrl/2-t-7] #CONTAINER#@T: java.lang.IllegalStateException: Send error reponse for the canceled request.\n at com.micromethod.sipmethod.server.sip.message.SipServletResponseImpl.send(SipServletResponseImpl.java:395)\n at com.voxeo.moho.sip.SIPCallImpl.disconnect(SIPCallImpl.java:855)\n at com.voxeo.moho.sip.ClearResourceSessionListener.sessionDestroyed(ClearResourceSessionListener.java:24)\n at com.micromethod.sipmethod.server.container.impl.ApplicationSupport.handleEvent(ApplicationSupport.java:238)\n at com.micromethod.sipmethod.server.container.impl.ApplicationImpl.handleEvent(ApplicationImpl.java:548)\n at com.micromethod.sipmethod.server.sip.session.SipSessionImpl.invalidate(SipSessionImpl.java:347)\n at com.micromethod.sipmethod.server.sip.session.SipSessionImpl.processInvalidationWhenReady(SipSessionImpl.java:409)\n at com.micromethod.sipmethod.server.sip.session.SipApplicationSessionImpl.processSipSessionInvalidationWhenReady(SipApplicationSessionImpl.java:325)\n at com.micromethod.sipmethod.server.sip.session.SipSessionImpl.processInvalidationWhenReady(SipSessionImpl.java:1650)\n at com.micromethod.sipmethod.server.sip.session.ApplicationSelectionLayer.out(ApplicationSelectionLayer.java:352)\n at com.micromethod.sipmethod.server.sip.session.SessionManagementLayer._out(SessionManagementLayer.java:170)\n at com.micromethod.sipmethod.server.sip.interceptor.SipInterceptorChainImpl.invokeNextPostApplication(SipInterceptorChainImpl.java:101)\n at com.micromethod.sipmethod.server.sip.interceptor.SipInterceptorSupport.postApplication(SipInterceptorSupport.java:31)\n at com.micromethod.sipmethod.server.sip.interceptor.SipInterceptorChainImpl.invokeNextPostApplication(SipInterceptorChainImpl.java:91)\n at com.micromethod.sipmethod.server.sip.interceptor.SipInterceptorPipelineImpl.postInvokeApplication(SipInterceptorPipelineImpl.java:40)\n at com.micromethod.sipmethod.server.sip.session.SessionManagementLayer.out(SessionManagementLayer.java:91)\n at com.micromethod.sipmethod.server.sip.impl.ApplicationDispatchLayer.out(ApplicationDispatchLayer.java:132)\n at com.micromethod.sipmethod.server.sip.message.SipServletResponseImpl.send(SipServletResponseImpl.java:408)\n at com.voxeo.moho.sip.SIPHelper.handleErrorSdpPortManagerEvent(SIPHelper.java:188)\n at com.voxeo.moho.sip.Media2NIJoinDelegate.doSdpEvent(Media2NIJoinDelegate.java:56)\n at com.voxeo.moho.sip.SIPCallImpl.onEvent(SIPCallImpl.java:674)\n at com.voxeo.moho.sip.SIPIncomingCall.onEvent(SIPIncomingCall.java:123)\n at com.voxeo.moho.sip.SIPIncomingCall.onEvent(SIPIncomingCall.java:30)\n at com.voxeo.mscontrol.MediaEventNotifierSupport.notify(MediaEventNotifierSupport.java:61)\n at com.voxeo.mscontrol.networkconnection.MrcpSdpPortManager$CreateThread.run(MrcpSdpPortManager.java:259)\n at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)\n at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)\n at java.lang.Thread.run(Thread.java:680)\n | |
| 2011-06-04 00:05:07.660 DEBUG [msctrl/2-t-7] #SIP#: Remove Path[ id=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.-ae339368-8286b5cien81, session=SipSessionImpl[_id=ss_1jr6l7qq24x4g, _parentId=sas_2-15-sm13ejiylruck25tropo2, _handler=Controller, _state=TERMINATED, _fresh=false, _valid=true, _unavailable=true, _role=UAS, _method=INVITE, _callId=YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.]] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.660 DEBUG [msctrl/2-t-7] #SIP#: processInvalidationWhenReady [sas_2-15-sm13ejiylruck25tropo2], invalidateWhenReady: true | |
| 2011-06-04 00:05:07.660 DEBUG [msctrl/2-t-7] #SIP#: Container will automatically invalidate SipApplicationSession [sas_2-15-sm13ejiylruck25tropo2] | |
| 2011-06-04 00:05:07.661 DEBUG [udp/0.0.0.0/5060-t-3] #SIP#: (i)[ACK sip:[email protected]:5060 SIP/2.0\r\nTo: <sip:[email protected]:5060>;tag=8286b5cien81\r\nVia: SIP/2.0/UDP 127.0.0.1:15978;branch=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-;rport\r\nCSeq: 1 ACK\r\nContent-Length: 0\r\nCall-ID: YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.\r\nFrom: <sip:[email protected]>;tag=ae339368\r\nMax-Forwards: 70\r\n\r\n] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:07.664 DEBUG [msctrl/2-t-7] #SIP#: SipApplicationSessionImpl[_state=VALID, _id=sas_2-15-sm13ejiylruck25tropo2] is invalidating. (as) | |
| 2011-06-04 00:05:07.665 INFO [pool-16-thread-12] Reply [com.voxeo.moho.SignalException: com.voxeo.moho.NegotiateException] | |
| 2011-06-04 00:05:07.667 DEBUG [pool-16-thread-12] Mapping unknown exception [type=class com.voxeo.moho.SignalException, message=com.voxeo.moho.NegotiateException] | |
| 2011-06-04 00:05:07.667 DEBUG [pool-16-thread-12] #XMPP#: (o)<iq type="error" to="[email protected]/voxeo" id="blather000b" from="[email protected]"><answer xmlns="urn:xmpp:ozone:1"/><error type="cancel"><internal-server-error xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xmlns="urn:ietf:params:xml:ns:xmpp-stanzas" xml:lang="en">com.voxeo.moho.NegotiateException</text></error></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:07.667 DEBUG [pool-16-thread-12] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:07.668 ERROR [pool-16-thread-12] Exception processing command | |
| java.lang.reflect.InvocationTargetException | |
| at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) | |
| at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) | |
| at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) | |
| at java.lang.reflect.Method.invoke(Method.java:597) | |
| at com.tropo.server.ReflectiveActor.onMessage(ReflectiveActor.java:65) | |
| at org.jetlang.channels.ChannelSubscription$1.run(ChannelSubscription.java:31) | |
| at org.jetlang.core.BatchExecutorImpl.execute(BatchExecutorImpl.java:11) | |
| at org.jetlang.fibers.PoolFiber.flush(PoolFiber.java:63) | |
| at org.jetlang.fibers.PoolFiber.access$000(PoolFiber.java:19) | |
| at org.jetlang.fibers.PoolFiber$1.run(PoolFiber.java:36) | |
| at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) | |
| at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) | |
| at java.lang.Thread.run(Thread.java:680) | |
| Caused by: com.voxeo.moho.SignalException: com.voxeo.moho.NegotiateException | |
| at com.voxeo.moho.sip.SIPCallImpl.answer(SIPCallImpl.java:1792) | |
| at com.voxeo.moho.event.InviteEvent.answer(InviteEvent.java:253) | |
| at com.tropo.server.CallActor.answer(CallActor.java:192) | |
| ... 13 more | |
| Caused by: com.voxeo.moho.NegotiateException | |
| at com.voxeo.moho.sip.Media2NIJoinDelegate.doSdpEvent(Media2NIJoinDelegate.java:57) | |
| at com.voxeo.moho.sip.SIPCallImpl.onEvent(SIPCallImpl.java:674) | |
| at com.voxeo.moho.sip.SIPIncomingCall.onEvent(SIPIncomingCall.java:123) | |
| at com.voxeo.moho.sip.SIPIncomingCall.onEvent(SIPIncomingCall.java:30) | |
| at com.voxeo.mscontrol.MediaEventNotifierSupport.notify(MediaEventNotifierSupport.java:61) | |
| at com.voxeo.mscontrol.networkconnection.MrcpSdpPortManager$CreateThread.run(MrcpSdpPortManager.java:259) | |
| ... 3 more | |
| 2011-06-04 00:05:07.671 INFO [pool-16-thread-12] Event [EndEvent[callId=86796ca8-5f96-44fb-9c35-4507e9d324e9,reason=ERROR,errorText=<null>,headers=<null>]] | |
| 2011-06-04 00:05:07.671 DEBUG [pool-16-thread-12] #XMPP#: (o)<iq type="set" from="[email protected]" to="[email protected]/voxeo" id="9d0068d3-0013-4e9f-9ca4-9e204869b0ae"><end xmlns="urn:xmpp:ozone:1"><error/></end></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:07.671 DEBUG [pool-16-thread-12] #XMPP#: Send message to: 127.0.0.1:53822 by xmpp #[tropo2][null][xmpps_15q43a2hbj6s4][[email protected]/voxeo, 127.0.0.1][127.0.0.1:53822][127.0.0.1:5222][xmpp][N/A] | |
| 2011-06-04 00:05:07.672 INFO [pool-16-thread-12] Call cleanup [call=SIPCallImpl[SipSessionAdaptor[sessionid=ss_1jr6l7qq24x4g, appSessionId=sas_2-15-sm13ejiylruck25tropo2],FAILED]] | |
| 2011-06-04 00:05:07.673 INFO [pool-16-thread-11] Actor is disposed. Ignoring message. [[Event class=com.voxeo.moho.event.JoinCompleteEvent sourceClass=SIPIncomingCall]] | |
| 2011-06-04 00:05:07.673 INFO [pool-16-thread-11] Actor is disposed. Ignoring message. [[Event class=com.voxeo.moho.event.CallCompleteEvent sourceClass=SIPIncomingCall]] | |
| 2011-06-04 00:05:12.665 DEBUG [Timer-12] #SIP#: InviteServerTransaction[_state=CONFIRMED, _id=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-] timerRemoveTransaction is fired. #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] | |
| 2011-06-04 00:05:12.666 DEBUG [Timer-12] #SIP#: InviteServerTransaction[_state=TERMINATED, _id=z9hG4bK-d8754z-5021101bdd47d118-1---d8754z-] is released. #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:[email protected](127.0.0.1:15978)][sip:[email protected]:5060(192.168.0.196:5060)] |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment
Looks like your media server is dead.
2011-06-04 00:05:07.648 ERROR [msctrl/2-t-7] #MRCP#: ERROR_CREATING_RTSP_CONNECTION@T: java.io.IOException: com.mot.mrcp.mrcpv1.rtsp.RtspConnection@1973d30 connect timeout after 5000\n at com.mot.mrcp.mrcpv1.rtsp.RtspConnection.(RtspConnection.java:74)\n at com.mot.mrcp.mrcpv1.rtsp.RtspClient.newRtspConnection(RtspClient.java:53)\n at com.mot.mrcp.mrcpv1.client.MrcpClient.initAsrResource(MrcpClient.java:519)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpAsrSessionImpl.(MrcpAsrSessionImpl.java:145)\n at com.voxeo.sipmethod.mrcp.client.impl.MrcpClientImpl.getAsrSession(MrcpClientImpl.java:106)\n at com.voxeo.mscontrol.networkconnection.MrcpNetworkConnection.initMRCPSession(MrcpNetworkConnection.java:104)\n at com.voxeo.mscontrol.networkconnection.MrcpSdpPortManager$CreateThread.run(MrcpSdpPortManager.java:245)\n at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)\n at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)\n at java.lang.Thread.run(Thread.java:680)\n
Try to restart PRISM Media Server