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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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][usera@127.0.0.1, 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="usera@127.0.0.1"><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 usera@127.0.0.1/voxeo to session XMPPSessionImpl: _id=xmpps_15q43a2hbj6s4, _direction=RECEIVEStream, _sessionType=CLIENT, _remotJID=usera@127.0.0.1/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][usera@127.0.0.1/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="usera@127.0.0.1"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>usera@127.0.0.1/voxeo</jid></bind></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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 [jid=usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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="usera@127.0.0.1/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="usera@127.0.0.1/voxeo"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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="usera@127.0.0.1/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="usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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][usera@127.0.0.1/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:usera@127.0.0.1:5060 SIP/2.0\r\nContent-Type: application/sdp\r\nTo: <sip:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368\r\nMax-Forwards: 70\r\nContact: <sip:16508983130@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368\r\n\r\n] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:usera@127.0.0.1:5060(127.0.0.1:15978)][sip:16508983130@127.0.0.1(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:usera@127.0.0.1:5060(127.0.0.1:15978)][sip:16508983130@127.0.0.1(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:usera@127.0.0.1:5060(127.0.0.1:15978)][sip:16508983130@127.0.0.1(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:usera@127.0.0.1:5060, StateInfo=tropo2] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1,to=sip:usera@127.0.0.1:5060,headers={Max-Forwards=70, Content-Length=426, Contact=<sip:16508983130@127.0.0.1:15978>, Supported=replaces, Allow=INVITE, To=<sip:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368}]] | |
| 2011-06-04 00:05:02.502 DEBUG [pool-16-thread-12] #XMPP#: (o)<iq type="set" from="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" to="usera@127.0.0.1/voxeo" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900"><offer xmlns="urn:xmpp:ozone:1" to="sip:usera@127.0.0.1:5060" from="sip:16508983130@127.0.0.1"><header name="Max-Forwards" value="70"/><header name="Content-Length" value="426"/><header name="Contact" value="<sip:16508983130@127.0.0.1:15978>"/><header name="Supported" value="replaces"/><header name="Allow" value="INVITE"/><header name="To" value="<sip:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368"/></offer></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900" from="usera@127.0.0.1/voxeo"/> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" id="blather000b" from="usera@127.0.0.1/voxeo"><answer xmlns="urn:xmpp:ozone:1"/></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" id="87ce9d77-a1ee-42d5-ba31-923e3b96b900" from="usera@127.0.0.1/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][usera@127.0.0.1/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="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" id="blather000b" from="usera@127.0.0.1/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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368\r\n\r\n] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368\r\n\r\n] #[tropo2][sas_2-15-sm13ejiylruck25tropo2][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][ss_1jr6l7qq24x4g][sip:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:usera@127.0.0.1:5060 SIP/2.0\r\nTo: <sip:usera@127.0.0.1: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:16508983130@127.0.0.1>;tag=ae339368\r\nMax-Forwards: 70\r\n\r\n] #[N/A][N/A][YWJlZmM5MzFhMjhlM2U4MDc4YjgzMmUyZGY1YmRkZjg.][N/A][sip:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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="usera@127.0.0.1/voxeo" id="blather000b" from="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1"><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][usera@127.0.0.1/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][usera@127.0.0.1/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="86796ca8-5f96-44fb-9c35-4507e9d324e9@127.0.0.1" to="usera@127.0.0.1/voxeo" id="9d0068d3-0013-4e9f-9ca4-9e204869b0ae"><end xmlns="urn:xmpp:ozone:1"><error/></end></iq> #[tropo2][null][xmpps_15q43a2hbj6s4][usera@127.0.0.1/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][usera@127.0.0.1/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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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:16508983130@127.0.0.1(127.0.0.1:15978)][sip:usera@127.0.0.1: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