[reSIProcate] Strange issue: can't received the re-INVITE message
Karlsson
boost.regex at gmail.com
Mon Jul 20 05:55:36 CDT 2009
Hi all, I got a very strange issue with 1.5, after the call is established,
when the caller(UAC) use re-INVITE to HOLD the call,
the callee can't received the re-INVITE message - but the re-INVITE message
is appears in the wireshark log.
I have attached the log files in mail, log1.pcap of the callee, we can saw
the re-INVITE message is arrived, but in the stack log - debug-log.txt,
we can't saw this INVITE.
I have tested with eyeBeam, got the same result.
Thanks
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://list.resiprocate.org/pipermail/resiprocate-devel/attachments/20090720/df914261/attachment.htm>
-------------- next part --------------
INFO | 20090720-184357.007 | MY TEL | RESIP:DNS | 324 | dnsutil.cxx:145 | local hostname does not contain a domain part chuxue-PC
DEBUG | 20090720-184357.008 | MY TEL | RESIP:DUM | 324 | BaseCreator.cxx:155 | BaseCreator::makeInitialRequest:
1
DEBUG | 20090720-184357.008 | MY TEL | RESIP:DUM | 324 | RegistrationCreator.cxx:36 | RegistrationCreator::RegistrationCreator: 1
DEBUG | 20090720-184357.008 | MY TEL | RESIP:DUM | 324 | DialogSet.cxx:53 | ************* Created DialogSet(UAC) -- MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256*************
DEBUG | 20090720-184357.008 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:867 | SEND:
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-e6738a181f097e41-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 1 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184357.009 | MY TEL | RESIP:DUM | 324 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-
DEBUG | 20090720-184357.009 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:1001 | Send: SipReq: REGISTER 125.22.86.19 tid=e6738a181f097e41 cseq=REGISTER contact=9199025012 / 1 from(tu)
DEBUG | 20090720-184357.009 | MY TEL | RESIP | 324 | sipstack.cxx:316 | SEND: SipReq: REGISTER 125.22.86.19 tid=e6738a181f097e41 cseq=REGISTER contact=9199025012 / 1 from(tu)
DEBUG | 20090720-184357.034 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer F tid=e6738a181f097e41 ms=32000
DEBUG | 20090720-184357.034 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:316 | Looking up dns entries for sip:125.22.86.19
DEBUG | 20090720-184357.034 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:207 | DnsResult::lookup sip:125.22.86.19
DEBUG | 20090720-184357.034 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:391 | Numeric result so return immediately: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184357.034 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=e6738a181f097e41 ms=500
DEBUG | 20090720-184357.038 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] -> [ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20090720-184357.038 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1107 | findTransportBySource([ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ])
DEBUG | 20090720-184357.038 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184357.039 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=125.22.86.19 mFlowKey=0 ]
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-e6738a181f097e41-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 1 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184357.039 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184357.534 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=e6738a181f097e41 ms=1000
DEBUG | 20090720-184357.534 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184357.610 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184357.610 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184357.610 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-e6738a181f097e41-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 1 REGISTER
WWW-Authenticate: Digest realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",opaque="",stale=FALSE,algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184357.610 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer K tid=e6738a181f097e41 ms=5000
INFO | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 401 tid=e6738a181f097e41 cseq=REGISTER / 1 from(wire)
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 401 tid=e6738a181f097e41 cseq=REGISTER / 1 from(wire)
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:229 | ClientAuthManager::RealmState::handleAuth: 0605AE38 Digest realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",opaque="",stale=FALSE,algorithm=MD5 is proxy: 0
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:203 | ClientAuthManager::RealmState::transition from invalid to current
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | UserProfile.cxx:158 | Found credential for realm: realm=125.22.86.19 user=9199025012125.22.86.19
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:63 | Produced response to digest challenge for UserProfile: "9199025012"<sip:9199025012 at 125.22.86.19>[realm=125.22.86.19 user=9199025012]
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:253 | about to re-send request with digest credentials
DEBUG | 20090720-184357.634 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:324 | Add auth, 0605AE38 in response to: Digest realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",opaque="",stale=FALSE,algorithm=MD5
DEBUG | 20090720-184357.635 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:349 | ClientAuthManager::RealmState::addAuthentication, proxy: 0 Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
DEBUG | 20090720-184357.635 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-be138f019d491335-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 2 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184357.636 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-
DEBUG | 20090720-184357.636 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1001 | Send: SipReq: REGISTER 125.22.86.19 tid=be138f019d491335 cseq=REGISTER contact=9199025012 / 2 from(tu)
DEBUG | 20090720-184357.636 | MY TEL | RESIP | 3672 | sipstack.cxx:316 | SEND: SipReq: REGISTER 125.22.86.19 tid=be138f019d491335 cseq=REGISTER contact=9199025012 / 2 from(tu)
DEBUG | 20090720-184357.660 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer F tid=be138f019d491335 ms=32000
DEBUG | 20090720-184357.660 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:316 | Looking up dns entries for sip:125.22.86.19
DEBUG | 20090720-184357.660 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:207 | DnsResult::lookup sip:125.22.86.19
DEBUG | 20090720-184357.660 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:391 | Numeric result so return immediately: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184357.660 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=be138f019d491335 ms=500
DEBUG | 20090720-184357.664 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] -> [ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20090720-184357.664 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1107 | findTransportBySource([ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ])
DEBUG | 20090720-184357.664 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184357.664 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=125.22.86.19 mFlowKey=0 ]
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-be138f019d491335-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 2 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184357.665 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184358.104 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.160 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=be138f019d491335 ms=1000
DEBUG | 20090720-184358.160 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184358.255 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.255 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E2 tid=be138f019d491335 ms=4000
DEBUG | 20090720-184358.255 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184358.255 | MY TEL | RESIP:DNS | 1692 | rrvip.cxx:128 | updating an existing vip: 125.22.86.19 with 125.22.86.19
DEBUG | 20090720-184358.255 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-be138f019d491335-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 2 REGISTER
Content-Length: 0
INFO | 20090720-184358.266 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 100 tid=be138f019d491335 cseq=REGISTER / 2 from(wire)
DEBUG | 20090720-184358.266 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 100 tid=be138f019d491335 cseq=REGISTER / 2 from(wire)
DEBUG | 20090720-184358.281 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.281 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184358.281 | MY TEL | RESIP:DNS | 1692 | rrvip.cxx:128 | updating an existing vip: 125.22.86.19 with 125.22.86.19
DEBUG | 20090720-184358.281 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-be138f019d491335-1---d8754z-;rport
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>;expires=50
To: <sip:9199025012 at 125.22.86.19>;tag=1248086639414
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 2 REGISTER
Expires: 50
Content-Length: 0
DEBUG | 20090720-184358.281 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer K tid=be138f019d491335 ms=5000
INFO | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 200 tid=be138f019d491335 cseq=REGISTER contact=9199025012 at 192.168.1.188:6211 / 2 from(wire)
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 200 tid=be138f019d491335 cseq=REGISTER contact=9199025012 at 192.168.1.188:6211 / 2 from(wire)
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256to cached
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:203 | ClientAuthManager::RealmState::transition from current to cached
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-1248086639414
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:19 | First keep alive for: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:20 | Keepalive interval 30 seconds
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:21 | Keepalive id 0
DEBUG | 20090720-184358.296 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ](0)
DEBUG | 20090720-184358.296 | MY TEL | RESIP:DUM | 3672 | ClientRegistration.cxx:403 | Clearing service route ([])
DEBUG | 20090720-184358.296 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: DumTimeout::Registration ClientRegistration sip:9199025012 at 125.22.86.19: duration=45 seq=1
DEBUG | 20090720-184358.297 | MY TEL | RESIP:DUM | 3672 | BaseCreator.cxx:155 | BaseCreator::makeInitialRequest:
1
DEBUG | 20090720-184358.298 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:53 | ************* Created DialogSet(UAC) -- MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361*************
DEBUG | 20090720-184358.298 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
SUBSCRIBE sip:9199025012 at 125.22.86.19 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-8a74cc346a58c413-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012>
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 1 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Event: message-summary
Content-Length: 0
DEBUG | 20090720-184358.299 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361-
DEBUG | 20090720-184358.299 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1001 | Send: SipReq: SUBSCRIBE 9199025012 at 125.22.86.19 tid=8a74cc346a58c413 cseq=SUBSCRIBE contact=9199025012 / 1 from(tu)
DEBUG | 20090720-184358.299 | MY TEL | RESIP | 3672 | sipstack.cxx:316 | SEND: SipReq: SUBSCRIBE 9199025012 at 125.22.86.19 tid=8a74cc346a58c413 cseq=SUBSCRIBE contact=9199025012 / 1 from(tu)
DEBUG | 20090720-184358.306 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer F tid=8a74cc346a58c413 ms=32000
DEBUG | 20090720-184358.306 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:316 | Looking up dns entries for sip:9199025012 at 125.22.86.19
DEBUG | 20090720-184358.306 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:207 | DnsResult::lookup sip:9199025012 at 125.22.86.19
DEBUG | 20090720-184358.306 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:391 | Numeric result so return immediately: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184358.306 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=8a74cc346a58c413 ms=500
DEBUG | 20090720-184358.310 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] -> [ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20090720-184358.310 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1107 | findTransportBySource([ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ])
DEBUG | 20090720-184358.310 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.310 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=125.22.86.19 mFlowKey=0 ]
SUBSCRIBE sip:9199025012 at 125.22.86.19 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-8a74cc346a58c413-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012 at 192.168.1.188:6211>
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 1 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Event: message-summary
Content-Length: 0
DEBUG | 20090720-184358.311 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184358.732 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.753 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.806 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=8a74cc346a58c413 ms=1000
DEBUG | 20090720-184358.806 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184358.880 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.881 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184358.881 | MY TEL | RESIP:DNS | 1692 | rrvip.cxx:128 | updating an existing vip: 125.22.86.19 with 125.22.86.19
DEBUG | 20090720-184358.881 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 501 Not implemented
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-8a74cc346a58c413-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 1 SUBSCRIBE
Content-Length: 0
DEBUG | 20090720-184358.881 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer K tid=8a74cc346a58c413 ms=5000
INFO | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 501 tid=8a74cc346a58c413 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 501 tid=8a74cc346a58c413 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361-
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:791 | mState == 3 Creating a new Dialog from msg:
SIP/2.0 501 Not implemented
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-8a74cc346a58c413-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 1 SUBSCRIBE
Content-Length: 0
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361-
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:232 | ************** Created Dialog as UAC **************
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:233 | mRemoteNameAddr: <sip:9199025012 at 125.22.86.19>
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:234 | mLocalNameAddr: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:235 | mLocalContact: <sip:>
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:236 | mRemoteTarget: <sip:>
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:239 | Dialog::Dialog MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361-
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:838 | ### Calling CreateAppDialog ###:
SIP/2.0 501 Not implemented
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-8a74cc346a58c413-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 1 SUBSCRIBE
Content-Length: 0
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:370 | Dialog::dispatch: SipResp: 501 tid=8a74cc346a58c413 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20090720-184358.899 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:40 | Association added for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.900 | MY TEL | RESIP:DUM | 3672 | ClientSubscription.cxx:33 | ClientSubscription::ClientSubscription from SipReq: SUBSCRIBE 9199025012 at 125.22.86.19 tid=8a74cc346a58c413 cseq=SUBSCRIBE contact=9199025012 / 1 from(tu)
DEBUG | 20090720-184358.900 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:1013 | Dialog::makeRequest:
SUBSCRIBE sip: SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-cb7f682a0b37fd5b-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:>
To: <sip:9199025012 at 125.22.86.19>
From: "9199025012"<sip:9199025012 at 125.22.86.19>;tag=6319d361
Call-ID: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.
CSeq: 2 SUBSCRIBE
Event: message-summary
Content-Length: 0
DEBUG | 20090720-184358.900 | MY TEL | RESIP:DUM | 3672 | ClientSubscription.cxx:59 | ClientSubscription::dispatch SipResp: 501 tid=8a74cc346a58c413 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20090720-184358.900 | MY TEL | RESIP:DUM | 3672 | ClientSubscription.cxx:114 | processing client subscription response
INFO | 20090720-184358.900 | MY TEL | RESIP:DUM | 3672 | ClientSubscription.cxx:505 | End subscription: sip:
DEBUG | 20090720-184358.901 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:244 | Dialog::~Dialog()
DEBUG | 20090720-184358.901 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:49 | Association removed for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184358.902 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:123 | ********** DialogSet::~DialogSet: MDI3Y2QxZTljOTdiMjdlNjk2MjIyZjk5OWY2ZWEyYzg.-6319d361*************
DEBUG | 20090720-184359.378 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184410.178 | MY TEL | RESIP | 1692 | sipmessage.cxx:897 | 2 extra bytes after body. Ignoring these bytes.
DEBUG | 20090720-184410.178 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184410.178 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:66 | Checking if SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire) is for me
DEBUG | 20090720-184410.178 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:71 | Checking rule...
DEBUG | 20090720-184410.178 | MY TEL | RESIP:TRANSACTION | 1692 | messagefilterrule.cxx:42 | Matching rule for:
INVITE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3 2 IN IP4 125.22.86.19
s=CounterPath eyeBeam 1.5
c=IN IP4 125.22.86.19
t=0 0
m=audio 20376 RTP/AVP 107 100 106 6 0 105 8 18 3 5 101
a=alt:1 1 : RATSpnUb 4d/5zS8T 192.168.1.198 5674
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:9B66355A56BB48DE8BC1CFB616D794FC
DEBUG | 20090720-184410.178 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:74 | Match!
DEBUG | 20090720-184410.178 | MY TEL | RESIP | 1692 | helper.cxx:375 | Helper::makeResponse(SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire) code=100 reason=
DEBUG | 20090720-184410.179 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer Trying tid=1248086651227 ms=80
DEBUG | 20090720-184410.179 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
INVITE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3 2 IN IP4 125.22.86.19
s=CounterPath eyeBeam 1.5
c=IN IP4 125.22.86.19
t=0 0
m=audio 20376 RTP/AVP 107 100 106 6 0 105 8 18 3 5 101
a=alt:1 1 : RATSpnUb 4d/5zS8T 192.168.1.198 5674
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:9B66355A56BB48DE8BC1CFB616D794FC
INFO | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1653 | DialogUsageManager::processRequest: SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:85 | ************* Created DialogSet(UAS) -- NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658*************
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-9d4c4e22-180bff76
DEBUG | 20090720-184410.182 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:791 | mState == 3 Creating a new Dialog from msg:
INVITE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3 2 IN IP4 125.22.86.19
s=CounterPath eyeBeam 1.5
c=IN IP4 125.22.86.19
t=0 0
m=audio 20376 RTP/AVP 107 100 106 6 0 105 8 18 3 5 101
a=alt:1 1 : RATSpnUb 4d/5zS8T 192.168.1.198 5674
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:9B66355A56BB48DE8BC1CFB616D794FC
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:87 | UAS dialog ID creation, DS: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:63 | DialogId::DialogId: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658-180bff76
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:141 | ************** Created Dialog as UAS **************
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:142 | mRemoteNameAddr: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:143 | mLocalNameAddr: <sip:9199025012 at 218.76.20.175>;tag=766da658
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:144 | mLocalContact: <sip:9199025012 at 218.76.20.175:45151>
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:145 | mRemoteTarget: <sip:125.22.86.19:5060>
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:239 | Dialog::Dialog NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658-180bff76
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:838 | ### Calling CreateAppDialog ###:
INVITE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3 2 IN IP4 125.22.86.19
s=CounterPath eyeBeam 1.5
c=IN IP4 125.22.86.19
t=0 0
m=audio 20376 RTP/AVP 107 100 106 6 0 105 8 18 3 5 101
a=alt:1 1 : RATSpnUb 4d/5zS8T 192.168.1.198 5674
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:9B66355A56BB48DE8BC1CFB616D794FC
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:370 | Dialog::dispatch: SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:40 | Association added for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184410.183 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:402 | Dialog::dispatch -- Created new server invite sessionSipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184410.184 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:77 | ^^^ InviteSession::InviteSession 0606B110
DEBUG | 20090720-184410.184 | MY TEL | RESIP | 3672 | sipmessage.cxx:1004 | SipMessage::getContents: application/sdp
DEBUG | 20090720-184410.184 | MY TEL | RESIP | 3672 | helper.cxx:2115 | Got sdp
INFO | 20090720-184410.186 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:2606 | Transition UAS_Start -> UAS_Offer
DEBUG | 20090720-184410.186 | MY TEL | RESIP:DUM | 3672 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
INFO | 20090720-184410.189 | MY TEL | RESIP:DUM | 3672 | ServerInviteSession.cxx:131 | UAS_Offer: provisional(180)
INFO | 20090720-184410.189 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:2606 | Transition UAS_Offer -> UAS_EarlyOffer
DEBUG | 20090720-184410.189 | MY TEL | RESIP | 3672 | helper.cxx:375 | Helper::makeResponse(SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire) code=180 reason=
DEBUG | 20090720-184410.189 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:1076 | Dialog::makeResponse:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Content-Length: 0
DEBUG | 20090720-184410.189 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: DumTimeout::Retransmit1xx INVITE: 8 UAS_EarlyOffer ADDR=<sip:9199025012 at 218.76.20.175>;tag=766da658 PEER="9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76: duration=60 seq=2
DEBUG | 20090720-184410.189 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184410.190 | MY TEL | RESIP | 3672 | sipstack.cxx:298 | SEND: SipResp: 180 tid=1248086651227 cseq=INVITE contact=9199025012 at 218.76.20.175:45151 / 2 from(tu)
DEBUG | 20090720-184410.210 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by=125.22.86.19 sent-port=5060
DEBUG | 20090720-184410.210 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184410.210 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184412.442 | MY TEL | RESIP:DUM | 324 | ServerInviteSession.cxx:321 | UAS_EarlyOffer: provideAnswer
INFO | 20090720-184412.442 | MY TEL | RESIP:DUM | 324 | InviteSession.cxx:2606 | Transition UAS_EarlyOffer -> UAS_EarlyProvidedAnswer
INFO | 20090720-184412.442 | MY TEL | RESIP:DUM | 324 | ServerInviteSession.cxx:513 | UAS_EarlyProvidedAnswer: accept(200)
INFO | 20090720-184412.442 | MY TEL | RESIP:DUM | 324 | InviteSession.cxx:2606 | Transition UAS_EarlyProvidedAnswer -> UAS_Accepted
DEBUG | 20090720-184412.442 | MY TEL | RESIP | 324 | helper.cxx:375 | Helper::makeResponse(SipReq: INVITE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=INVITE contact=125.22.86.19:5060 / 2 from(wire) code=200 reason=
DEBUG | 20090720-184412.443 | MY TEL | RESIP:DUM | 324 | Dialog.cxx:1076 | Dialog::makeResponse:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
Content-Length: 0
DEBUG | 20090720-184412.444 | MY TEL | RESIP:TRANSACTION | 324 | timerqueue.cxx:105 | Adding application timer: DumTimeout::Retransmit200 INVITE: 8 UAS_Accepted ADDR=<sip:9199025012 at 218.76.20.175>;tag=766da658 PEER="9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76: duration=500 seq=2
DEBUG | 20090720-184412.444 | MY TEL | RESIP:TRANSACTION | 324 | timerqueue.cxx:105 | Adding application timer: DumTimeout::WaitForAck INVITE: 8 UAS_Accepted ADDR=<sip:9199025012 at 218.76.20.175>;tag=766da658 PEER="9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76: duration=32000 seq=2
DEBUG | 20090720-184412.444 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:867 | SEND:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: MyTel
Content-Length: 147
v=0
o=- 18513323 18513323 IN IP4 192.168.1.188
s=Call
c=IN IP4 192.168.1.188
t=0 0
m=audio 20470 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
DEBUG | 20090720-184412.444 | MY TEL | RESIP | 324 | sipstack.cxx:298 | SEND: SipResp: 200 tid=1248086651227 cseq=INVITE contact=9199025012 at 218.76.20.175:45151 / 2 from(tu)
DEBUG | 20090720-184412.464 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by=125.22.86.19 sent-port=5060
DEBUG | 20090720-184412.464 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: MyTel
Content-Length: 147
v=0
o=- 18513323 18513323 IN IP4 192.168.1.188
s=Call
c=IN IP4 192.168.1.188
t=0 0
m=audio 20470 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
DEBUG | 20090720-184412.464 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184412.465 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer StaleServer tid=1248086651227 ms=32000
INFO | 20090720-184412.954 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:1104 | Retransmitting:
SipResp: 200 tid=1248086651227 cseq=INVITE contact=9199025012 at 218.76.20.175:45151 / 2 from(tu)
DEBUG | 20090720-184412.954 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: MyTel
Content-Length: 147
v=0
o=- 18513323 18513323 IN IP4 192.168.1.188
s=Call
c=IN IP4 192.168.1.188
t=0 0
m=audio 20470 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
DEBUG | 20090720-184412.954 | MY TEL | RESIP | 3672 | sipstack.cxx:298 | SEND: SipResp: 200 tid=1248086651227 cseq=INVITE contact=9199025012 at 218.76.20.175:45151 / 2 from(tu)
DEBUG | 20090720-184412.955 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: DumTimeout::Retransmit200 INVITE: 8 UAS_Accepted ADDR=<sip:9199025012 at 218.76.20.175>;tag=766da658 PEER="9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76: duration=1000 seq=2
DEBUG | 20090720-184412.979 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by=125.22.86.19 sent-port=5060
DEBUG | 20090720-184412.980 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-f16a6e5dbd506570-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: MyTel
Content-Length: 147
v=0
o=- 18513323 18513323 IN IP4 192.168.1.188
s=Call
c=IN IP4 192.168.1.188
t=0 0
m=audio 20470 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
DEBUG | 20090720-184412.980 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184413.728 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184413.728 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:345 | Someone sent us an ACK/200 with the same tid as the original INVITE. This is bad behavior, and should be corrected in the client.
DEBUG | 20090720-184413.728 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:66 | Checking if SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire) is for me
DEBUG | 20090720-184413.728 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:71 | Checking rule...
DEBUG | 20090720-184413.728 | MY TEL | RESIP:TRANSACTION | 1692 | messagefilterrule.cxx:42 | Matching rule for:
ACK sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-9811d949e521d458-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 ACK
Content-Length: 0
DEBUG | 20090720-184413.729 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:74 | Match!
DEBUG | 20090720-184413.729 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
ACK sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-9811d949e521d458-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 ACK
Content-Length: 0
INFO | 20090720-184413.735 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184413.735 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1653 | DialogUsageManager::processRequest: SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire)
INFO | 20090720-184413.735 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1713 | Handling in-dialog request: SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire)
DEBUG | 20090720-184413.735 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658-180bff76
DEBUG | 20090720-184413.735 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:484 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
ACK sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-9811d949e521d458-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:125.22.86.19:5060>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 2 ACK
Content-Length: 0
DEBUG | 20090720-184413.736 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:370 | Dialog::dispatch: SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire)
INFO | 20090720-184413.736 | MY TEL | RESIP:DUM | 3672 | ServerInviteSession.cxx:802 | dispatchAccepted: SipReq: ACK 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=ACK contact=125.22.86.19:5060 / 2 from(wire)
INFO | 20090720-184413.736 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:2606 | Transition UAS_Accepted -> InviteSession::Connected
INFO | 20090720-184421.371 | MY TEL | RESIP | 1692 | sipmessage.cxx:897 | 2 extra bytes after body. Ignoring these bytes.
DEBUG | 20090720-184421.371 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184428.319 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:67 | Refreshing keepalive for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184428.319 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:68 | Keepalive interval 30 seconds
DEBUG | 20090720-184428.319 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:69 | Keepalive id 0)
DEBUG | 20090720-184428.319 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ](0)
DEBUG | 20090720-184428.324 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by= sent-port=0
DEBUG | 20090720-184428.324 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184428.324 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184443.320 | MY TEL | RESIP:DUM | 3672 | ClientRegistration.cxx:280 | requesting refresh of ClientRegistration sip:9199025012 at 125.22.86.19
DEBUG | 20090720-184443.320 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:324 | Add auth, 0605AE38 in response to: Digest realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",opaque="",stale=FALSE,algorithm=MD5
DEBUG | 20090720-184443.320 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:349 | ClientAuthManager::RealmState::addAuthentication, proxy: 0 Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
DEBUG | 20090720-184443.320 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4f3e6347921e5613-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 3 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184443.321 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-
DEBUG | 20090720-184443.321 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1001 | Send: SipReq: REGISTER 125.22.86.19 tid=4f3e6347921e5613 cseq=REGISTER contact=9199025012 / 3 from(tu)
DEBUG | 20090720-184443.321 | MY TEL | RESIP | 3672 | sipstack.cxx:316 | SEND: SipReq: REGISTER 125.22.86.19 tid=4f3e6347921e5613 cseq=REGISTER contact=9199025012 / 3 from(tu)
DEBUG | 20090720-184443.346 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer F tid=4f3e6347921e5613 ms=32000
DEBUG | 20090720-184443.346 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:316 | Looking up dns entries for sip:125.22.86.19
DEBUG | 20090720-184443.346 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:207 | DnsResult::lookup sip:125.22.86.19
DEBUG | 20090720-184443.346 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:391 | Numeric result so return immediately: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184443.346 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=4f3e6347921e5613 ms=500
DEBUG | 20090720-184443.349 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] -> [ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20090720-184443.349 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1107 | findTransportBySource([ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ])
DEBUG | 20090720-184443.350 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184443.350 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=125.22.86.19 mFlowKey=0 ]
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-4f3e6347921e5613-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 3 REGISTER
Expires: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184443.350 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184443.846 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=4f3e6347921e5613 ms=1000
DEBUG | 20090720-184443.846 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184443.922 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184443.923 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E2 tid=4f3e6347921e5613 ms=4000
DEBUG | 20090720-184443.923 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184443.923 | MY TEL | RESIP:DNS | 1692 | rrvip.cxx:128 | updating an existing vip: 125.22.86.19 with 125.22.86.19
DEBUG | 20090720-184443.923 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-4f3e6347921e5613-1---d8754z-;rport
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 3 REGISTER
Content-Length: 0
DEBUG | 20090720-184443.949 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184443.949 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:199 | Whitelisting 125.22.86.19(1): 125.22.86.19
DEBUG | 20090720-184443.949 | MY TEL | RESIP:DNS | 1692 | rrvip.cxx:128 | updating an existing vip: 125.22.86.19 with 125.22.86.19
DEBUG | 20090720-184443.949 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=1
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-4f3e6347921e5613-1---d8754z-;rport
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>;expires=50
To: <sip:9199025012 at 125.22.86.19>;tag=1248086685083
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 3 REGISTER
Expires: 50
Content-Length: 0
DEBUG | 20090720-184443.949 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer K tid=4f3e6347921e5613 ms=5000
INFO | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 100 tid=4f3e6347921e5613 cseq=REGISTER / 3 from(wire)
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 100 tid=4f3e6347921e5613 cseq=REGISTER / 3 from(wire)
INFO | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipResp: 200 tid=4f3e6347921e5613 cseq=REGISTER contact=9199025012 at 192.168.1.188:6211 / 3 from(wire)
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 200 tid=4f3e6347921e5613 cseq=REGISTER contact=9199025012 at 192.168.1.188:6211 / 3 from(wire)
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256to cached
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | ClientAuthManager.cxx:203 | ClientAuthManager::RealmState::transition from cached to cached
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-1248086685083
DEBUG | 20090720-184443.952 | MY TEL | RESIP:DUM | 3672 | ClientRegistration.cxx:403 | Clearing service route ([])
DEBUG | 20090720-184443.952 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: DumTimeout::Registration ClientRegistration sip:9199025012 at 125.22.86.19: duration=45 seq=2
DEBUG | 20090720-184444.418 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184444.441 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184456.999 | MY TEL | RESIP:TRANSACTION | 1692 | tuselector.cxx:70 | Stats message
WARNING | 20090720-184456.999 | MY TEL | RESIP:STATS | 1692 | statisticsmessage.cxx:152 | RESIP:TRANSACTION
TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 0 SERVERTX 0 TIMERS 1
Transaction summary: reqi 3 reqo 8 rspi 12 rspo 3
Details: INVi 2/S2/F0 INVo 0/S0/F0 ACKi 1 ACKo 0 BYEi 0/S0/F0 BYEo 0/S0/F0 CANi 0/S0/F0 CANo 0/S0/F0 MSGi 0/S0/F0 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 0/S0/F0 REGo 3/S4/F2 PUBi 0/S0/F0 PUBo 0/S0/F0 SUBi 0/S0/F0 SUBo 2/S0/F2 NOTi 0/S0/F0 NOTo 0/S0/F0
Retransmissions: INVx 0 BYEx 0 CANx 0 MSGx 0 OPTx 0 REGx 3 finx 0 nonx 0 PUBx 0 SUBx 1 NOTx 0
DEBUG | 20090720-184458.330 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:67 | Refreshing keepalive for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184458.330 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:68 | Keepalive interval 30 seconds
DEBUG | 20090720-184458.330 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:69 | Keepalive id 0)
DEBUG | 20090720-184458.330 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ](0)
DEBUG | 20090720-184458.347 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by= sent-port=0
DEBUG | 20090720-184458.348 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184458.348 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:287 | incoming from: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:66 | Checking if SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire) is for me
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:71 | Checking rule...
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | messagefilterrule.cxx:42 | Matching rule for:
BYE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Max-Forwards: 70
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
Reason: SIP;description="User Hung Up"
Content-Length: 0
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | transactionuser.cxx:74 | Match!
DEBUG | 20090720-184501.306 | MY TEL | RESIP | 1692 | helper.cxx:375 | Helper::makeResponse(SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire) code=100 reason=
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer Trying tid=1248086651227 ms=3500
DEBUG | 20090720-184501.306 | MY TEL | RESIP:TRANSACTION | 1692 | transactionstate.cxx:2116 | Send to TU: TU: DialogUsageManager size=0
BYE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Max-Forwards: 70
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015" <sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
Reason: SIP;description="User Hung Up"
Content-Length: 0
INFO | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1335 | Got: SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire)
DEBUG | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1653 | DialogUsageManager::processRequest: SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire)
INFO | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:1713 | Handling in-dialog request: SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire)
DEBUG | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | DialogId.cxx:50 | DialogId::DialogId: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658-180bff76
DEBUG | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:484 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
BYE sip:9199025012 at 218.76.20.175:45151 SIP/2.0
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Max-Forwards: 70
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
Reason: SIP;description="User Hung Up"
Content-Length: 0
DEBUG | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:370 | Dialog::dispatch: SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire)
INFO | 20090720-184501.310 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:2020 | Received SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire)
DEBUG | 20090720-184501.310 | MY TEL | RESIP | 3672 | helper.cxx:375 | Helper::makeResponse(SipReq: BYE 9199025012 at 218.76.20.175:45151 tid=1248086651227 cseq=BYE / 4 from(wire) code=200 reason=
DEBUG | 20090720-184501.311 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:1076 | Dialog::makeResponse:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
Content-Length: 0
DEBUG | 20090720-184501.311 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:867 | SEND:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184501.312 | MY TEL | RESIP | 3672 | sipstack.cxx:298 | SEND: SipResp: 200 tid=1248086651227 cseq=BYE contact=9199025012 at 218.76.20.175:45151 / 4 from(tu)
INFO | 20090720-184501.312 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:2606 | Transition InviteSession::Connected -> InviteSession::Terminated
DEBUG | 20090720-184501.332 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer J tid=1248086651227 ms=32000
DEBUG | 20090720-184501.336 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] -> [ V4 192.168.1.188:0 UDP target domain=unspecified mFlowKey=868 ] sent-by=125.22.86.19 sent-port=5060
DEBUG | 20090720-184501.336 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=unspecified mFlowKey=868 ]
SIP/2.0 200 OK
Via: SIP/2.0/UDP 125.22.86.19:5060;branch=z9hG4bK1248086651227
Via: SIP/2.0/UDP 192.168.1.198:24590;branch=z9hG4bK-d8754z-a42a26343318fa16-1---d8754z-;rport
Contact: <sip:9199025012 at 218.76.20.175:45151>
To: <sip:9199025012 at 218.76.20.175>;tag=766da658
From: "9199025015"<sip:9199025015 at 125.22.86.19>;tag=180bff76
Call-ID: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.
CSeq: 4 BYE
User-Agent: MyTel
Content-Length: 0
DEBUG | 20090720-184501.336 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184501.392 | MY TEL | RESIP:DUM | 3672 | InviteSession.cxx:83 | ^^^ InviteSession::~InviteSession 0606B110
DEBUG | 20090720-184501.394 | MY TEL | RESIP:DUM | 3672 | Dialog.cxx:244 | Dialog::~Dialog()
DEBUG | 20090720-184501.394 | MY TEL | RESIP:DUM | 3672 | KeepAliveManager.cxx:49 | Association removed for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184501.395 | MY TEL | RESIP:DUM | 3672 | DialogUsageManager.cxx:2145 | Got merged request removal request
DEBUG | 20090720-184501.395 | MY TEL | RESIP:TRANSACTION | 3672 | timerqueue.cxx:105 | Adding application timer:
DEBUG | 20090720-184501.395 | MY TEL | RESIP:DUM | 3672 | DialogSet.cxx:123 | ********** DialogSet::~DialogSet: NWJkYTRjNGMxMGEwM2QzNzJhYzk3ODRjODU2MzUzYjY.-766da658*************
INFO | 20090720-184514.981 | MY TEL | RESIP:DUM | 324 | ClientRegistration.cxx:190 | Removing binding
DEBUG | 20090720-184514.981 | MY TEL | RESIP:DUM | 324 | ClientAuthManager.cxx:324 | Add auth, 0605AE38 in response to: Digest realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",opaque="",stale=FALSE,algorithm=MD5
DEBUG | 20090720-184514.982 | MY TEL | RESIP:DUM | 324 | ClientAuthManager.cxx:349 | ClientAuthManager::RealmState::addAuthentication, proxy: 0 Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
DEBUG | 20090720-184514.982 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:867 | SEND:
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-1979b51a01186654-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012;rinstance=860b72dd40013114>;expires=0
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 4 REGISTER
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184514.982 | MY TEL | RESIP:DUM | 324 | DialogId.cxx:50 | DialogId::DialogId: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256-
DEBUG | 20090720-184514.982 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:1001 | Send: SipReq: REGISTER 125.22.86.19 tid=1979b51a01186654 cseq=REGISTER contact=9199025012 / 4 from(tu)
DEBUG | 20090720-184514.982 | MY TEL | RESIP | 324 | sipstack.cxx:316 | SEND: SipReq: REGISTER 125.22.86.19 tid=1979b51a01186654 cseq=REGISTER contact=9199025012 / 4 from(tu)
DEBUG | 20090720-184514.989 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer F tid=1979b51a01186654 ms=32000
DEBUG | 20090720-184514.989 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:316 | Looking up dns entries for sip:125.22.86.19
DEBUG | 20090720-184514.989 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:207 | DnsResult::lookup sip:125.22.86.19
DEBUG | 20090720-184514.989 | MY TEL | RESIP:DNS | 1692 | dnsresult.cxx:391 | Numeric result so return immediately: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
DEBUG | 20090720-184514.989 | MY TEL | RESIP:TRANSACTION | 1692 | timerqueue.cxx:85 | Adding timer: Timer E1 tid=1979b51a01186654 ms=500
DEBUG | 20090720-184514.992 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:562 | Looked up source for destination: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] -> [ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20090720-184514.992 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1107 | findTransportBySource([ V4 192.168.1.188:0 UDP target domain=125.22.86.19 mFlowKey=0 ])
DEBUG | 20090720-184514.992 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184514.993 | MY TEL | RESIP:TRANSPORT | 1692 | transportselector.cxx:940 | Transmitting to [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.188:6211 UDP target domain=125.22.86.19 mFlowKey=0 ]
REGISTER sip:125.22.86.19 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.188:6211;branch=z9hG4bK-d8754z-1979b51a01186654-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:9199025012 at 192.168.1.188:6211;rinstance=860b72dd40013114>;expires=0
To: <sip:9199025012 at 125.22.86.19>
From: <sip:9199025012 at 125.22.86.19>;tag=dc61f256
Call-ID: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.
CSeq: 4 REGISTER
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Supported: replaces
User-Agent: MyTel
Authorization: Digest username="9199025012",realm="125.22.86.19",nonce="e052038a6e1c0e45872f9066a12584e2",uri="sip:125.22.86.19",response="824b8717f037d43ec52f2602f931b3ec",algorithm=MD5
Content-Length: 0
DEBUG | 20090720-184514.993 | MY TEL | RESIP:TRANSPORT | 1692 | transport.cxx:213 | Adding message to tx buffer to: [ V4 125.22.86.19:5060 UDP target domain=125.22.86.19 mFlowKey=0 ]
WARNING | 20090720-184515.080 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:250 | force shutdown
DEBUG | 20090720-184515.080 | MY TEL | RESIP:DUM | 324 | HandleManager.cxx:93 | Waiting for usages to be deleted (2)
DEBUG | 20090720-184515.080 | MY TEL | RESIP:DUM | 324 | HandleManager.cxx:96 | 1 -> AppDialogSet 1
DEBUG | 20090720-184515.080 | MY TEL | RESIP:DUM | 324 | HandleManager.cxx:96 | 2 -> ClientRegistration sip:9199025012 at 125.22.86.19
INFO | 20090720-184515.080 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:214 | DialogUsageManager::onAllHandlesDestroyed: removing TU
INFO | 20090720-184515.093 | MY TEL | RESIP:TRANSACTION | 1692 | tuselector.cxx:40 | TransactionUserMessage::RemoveTransactionUser TU: DialogUsageManager size=0
INFO | 20090720-184515.100 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:1143 | TU unregistered
WARNING | 20090720-184515.118 | MY TEL | RESIP | 1692 | stackthread.cxx:45 | Shutting down stack thread
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:137 | DialogUsageManager::mDialogSetMap has 1 DialogSets
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:141 | DialgSetId:MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | ClientRegistration.cxx:60 | ClientRegistration::~ClientRegistration
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | KeepAliveManager.cxx:49 | Association removed for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | KeepAliveManager.cxx:53 | Keepalive 0 removed
DEBUG | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | KeepAliveManager.cxx:54 | No more association for [ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]
INFO | 20090720-184515.710 | MY TEL | RESIP:DUM | 324 | DialogUsageManager.cxx:1043 | DialogUsageManager::destroy() not posting to stack
DEBUG | 20090720-184515.711 | MY TEL | RESIP:DUM | 324 | DialogSet.cxx:123 | ********** DialogSet::~DialogSet: MjBmOGYyMTNjODFhNTcwN2RjMjRlYjkzZTIwZGZmOWE.-dc61f256*************
DEBUG | 20090720-184515.711 | MY TEL | RESIP | 324 | sipstack.cxx:90 | SipStack::~SipStack()
DEBUG | 20090720-184515.711 | MY TEL | RESIP | 324 | security.cxx:915 | BaseSecurity::~BaseSecurity
DEBUG | 20090720-184515.711 | MY TEL | RESIP:TRANSACTION | 324 | transactionmap.cxx:18 | 1248086651227 -> 060FF3F8: tid=1248086651227 [ ServerNonInvite/Completed unreliable target=[ V4 125.22.86.19:5060 UDP target domain=unspecified mFlowKey=868 ]]
DEBUG | 20090720-184515.712 | MY TEL | RESIP:TRANSACTION | 324 | transactionmap.cxx:18 | 1979b51a01186654 -> 06059D68: tid=1979b51a01186654 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
INFO | 20090720-184515.712 | MY TEL | RESIP:TRANSPORT | 324 | udptransport.cxx:67 | Shutting down [ V4 0.0.0.0:6211 UDP target domain=unspecified mFlowKey=868 ]
-------------- next part --------------
A non-text attachment was scrubbed...
Name: log1.pcap
Type: application/octet-stream
Size: 9910 bytes
Desc: not available
URL: <http://list.resiprocate.org/pipermail/resiprocate-devel/attachments/20090720/df914261/attachment.obj>
More information about the resiprocate-devel
mailing list