reSIProcate

Mailing Lists

Source Code

Google Search:



< Previous by Date Date Index Next by Date >
< Previous in Thread Thread Index Next in Thread >

Re: [reSIProcate-users] The Dum says 400 Bad request when received aNOTIFY message

Thank you Socott, I have added the  presence handler already.


There are the logs:
INFO | 20080721-214556.745 | Mytest | RESIP:TRANSPORT | 1292 | UdpTransport.cxx:42 | Creating UDP transport host= port=5832 ipv4=1
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | InternalTransport.cxx:86 | Creating fd=660 V4/UDP
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | InternalTransport.cxx:94 | Binding to 0.0.0.0
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | UdpTransport.cxx:60 | No compression library available: Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | TransportSelector.cxx:187 | Adding transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=0 ]
DEBUG | 20080721-214556.756 | Mytest | RESIP:DUM | 1292 | UserProfile.cxx:135 | Adding credential: realm=iptel.org user=testuser1
DEBUG | 20080721-214556.757 | Mytest | RESIP:DUM | 1292 | UserProfile.cxx:135 | Adding credential: realm=iptel.org user=testuser1
INFO | 20080721-214556.758 | Mytest | RESIP:DNS | 1292 | dnsutil.cxx:145 | local hostname does not contain a domain part Solar
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | BaseCreator.cxx:140 | BaseCreator::makeInitialRequest: 049DAFB0
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | RegistrationCreator.cxx:31 | RegistrationCreator::RegistrationCreator: 049DAFB0
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:52 |  ************* Created DialogSet(UAC)  -- Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334*************
DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 90

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  REGISTER iptel.org tid=2a0d1f0f85157409 cseq=REGISTER contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214556.772 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=2a0d1f0f85157409 ms=32000
DEBUG | 20080721-214556.772 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214556.815 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214556.816 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=2a0d1f0f85157409 ms=500
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 90

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.316 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=2a0d1f0f85157409 ms=1000
DEBUG | 20080721-214557.316 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.329 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.329 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214557.329 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport=6869;received=118.249.70.70

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.8366

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 600

Min-Expires: 240

Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=446 req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:iptel.org out_uri=sip:iptel.org via_cnt==1"

WWW-Authenticate: Digest realm="iptel.org", nonce="48849323488493195018c1cb4abc781d84efbdd645340891"

Content-Length: 0




DEBUG | 20080721-214557.330 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=2a0d1f0f85157409 ms=5000
INFO | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 401 tid=2a0d1f0f85157409 cseq=REGISTER / 1 from(wire)
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 401 tid=2a0d1f0f85157409 cseq=REGISTER / 1 from(wire)
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04B87168 Digest realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891" is proxy: 0
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from invalid to current
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:63 | Produced response to digest challenge for UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | about to re-send request with digest credentials
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:319 |  Add auth, 04B87168 in response to: Digest realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891"
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, proxy: 0 Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 90

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  REGISTER iptel.org tid=7b44914a11580078 cseq=REGISTER contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214557.355 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=7b44914a11580078 ms=32000
DEBUG | 20080721-214557.355 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214557.355 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214557.355 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=7b44914a11580078 ms=500
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.359 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 90

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214557.359 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.823 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.855 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=7b44914a11580078 ms=1000
DEBUG | 20080721-214557.855 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.870 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.871 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214557.871 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214557.871 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport=6869;received=118.249.70.70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5316;rinstance=ff37316fef4b991b>;expires=85;received="sip:118.249.70.70:6864;dstip=213.192.59.75;dstport=5060"

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>;expires=240;received="sip:118.249.70.70:6869;dstip=213.192.59.75;dstport=5060"

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.2446

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 600

Min-Expires: 240

Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=446 req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:iptel.org out_uri=sip:iptel.org via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214557.871 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=7b44914a11580078 ms=5000
INFO | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 200 tid=7b44914a11580078 cseq=REGISTER contact=testuser1@xxxxxxxxxxxxx:5316 / 2 from(wire)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 200 tid=7b44914a11580078 cseq=REGISTER contact=testuser1@xxxxxxxxxxxxx:5316 / 2 from(wire)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334to cached
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from current to cached
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-b98f1230ac1a7949bf499aa7f9ea7038.2446
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | KeepAliveManager.cxx:19 | First keep alive for: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | KeepAliveManager.cxx:20 | Keepalive interval 35 seconds
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | KeepAliveManager.cxx:21 | Keepalive id 0
DEBUG | 20080721-214557.884 | Mytest | RESIP:TRANSACTION | 3536 | TimerQueue.cxx:105 | Adding application timer: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ](0)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | ClientRegistration.cxx:397 | Clearing service route ([])
DEBUG | 20080721-214557.885 | Mytest | RESIP:TRANSACTION | 3536 | TimerQueue.cxx:105 | Adding application timer: DumTimeout::Registration ClientRegistration sip:testuser1@xxxxxxxxx: duration=216 seq=1
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | BaseCreator.cxx:140 | BaseCreator::makeInitialRequest: 04B97730
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:52 |  ************* Created DialogSet(UAC)  -- ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b*************
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214557.887 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214557.887 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=ca443b51a31ccd30 cseq=SUBSCRIBE contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214557.896 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=ca443b51a31ccd30 ms=32000
DEBUG | 20080721-214557.896 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214557.896 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214557.896 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=ca443b51a31ccd30 ms=500
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.900 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214557.900 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.369 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.396 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=ca443b51a31ccd30 ms=1000
DEBUG | 20080721-214558.396 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.410 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.410 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214558.410 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214558.410 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a"

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.94ed

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 1 SUBSCRIBE

Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser1@xxxxxxxxx out_uri=sip:testuser1@xxxxxxxxx via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214558.411 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=ca443b51a31ccd30 ms=5000
INFO | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=ca443b51a31ccd30 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=ca443b51a31ccd30 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04BA06F0 Digest realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a" is proxy: 1
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from invalid to current
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:63 | Produced response to digest challenge for UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | about to re-send request with digest credentials
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:319 |  Add auth, 04BA06F0 in response to: Digest realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a"
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, proxy: 1 Digest username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5
DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=3f10b27dee371b0d cseq=SUBSCRIBE contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214558.436 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=3f10b27dee371b0d ms=32000
DEBUG | 20080721-214558.436 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214558.436 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214558.436 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=500
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214558.440 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.936 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=1000
DEBUG | 20080721-214558.936 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:66 | Checking if SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire) is for me
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:71 | Checking rule...
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | MessageFilterRule.cxx:42 | Matching rule for:

SUBSCRIBE sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=mV0BBwB1YWNfbmF0AQAAAA;lr=on>

Contact: <sip:testuser1@xxxxxxxxxxxxx:6869>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0

P-hint: usrloc applied




DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:74 | Match!
DEBUG | 20080721-214558.955 | Mytest | RESIP | 3516 | Helper.cxx:372 | Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire) code=100 reason=
DEBUG | 20080721-214558.956 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer Trying tid=601.ea9a0476.1 ms=3500
DEBUG | 20080721-214558.956 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SUBSCRIBE sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=mV0BBwB1YWNfbmF0AQAAAA;lr=on>

Contact: <sip:testuser1@xxxxxxxxxxxxx:6869>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0

P-hint: usrloc applied




INFO | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1617 | DialogUsageManager::processRequest: SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogSetId.cxx:34 | ********** Generated Local Tag ***********
INFO | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1885 | No handler for event package for SUBSCRIBE: message-summary
DEBUG | 20080721-214558.972 | Mytest | RESIP | 3536 | Helper.cxx:372 | Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire) code=489 reason=
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SIP/2.0 489 Event Package Not Supported

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=1b004f18

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214558.972 | Mytest | RESIP | 3536 | SipStack.cxx:289 | SEND: SipResp: 489 tid=601.ea9a0476.1 cseq=SUBSCRIBE / 2 from(tu)
INFO | 20080721-214558.973 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1725 | Rejecting request (unsupported package) SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.981 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer J tid=601.ea9a0476.1 ms=32000
DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=unspecified mFlowKey=660 ]

SIP/2.0 489 Event Package Not Supported

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=1b004f18

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.422 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.423 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.936 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=2000
DEBUG | 20080721-214559.936 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214601.936 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214601.936 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | BaseCreator.cxx:140 | BaseCreator::makeInitialRequest: 04B8D5A0
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:52 |  ************* Created DialogSet(UAC)  -- ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801*************
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: presence

Content-Length: 0




DEBUG | 20080721-214603.581 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-
DEBUG | 20080721-214603.581 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=fa6010448f69c01e cseq=SUBSCRIBE contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214603.586 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=fa6010448f69c01e ms=32000
DEBUG | 20080721-214603.586 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214603.586 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214603.586 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=fa6010448f69c01e ms=500
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214603.590 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: presence

Content-Length: 0




DEBUG | 20080721-214603.590 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.086 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=fa6010448f69c01e ms=1000
DEBUG | 20080721-214604.086 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.100 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.100 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214604.100 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214604.101 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd"

To: <sip:testuser2@xxxxxxxxx:5060>;tag=b98f1230ac1a7949bf499aa7f9ea7038.f2af

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 1 SUBSCRIBE

Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=445 req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser2@xxxxxxxxx:5060 out_uri=sip:testuser2@xxxxxxxxx:5060 via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214604.101 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=fa6010448f69c01e ms=5000
INFO | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=fa6010448f69c01e cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=fa6010448f69c01e cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04B92348 Digest realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd" is proxy: 1
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from invalid to current
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:63 | Produced response to digest challenge for UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | about to re-send request with digest credentials
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:319 |  Add auth, 04B92348 in response to: Digest realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd"
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, proxy: 1 Digest username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5
DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: presence

Content-Length: 0




DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-
DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=4236530a4e4f5c64 cseq=SUBSCRIBE contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214604.126 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=4236530a4e4f5c64 ms=32000
DEBUG | 20080721-214604.126 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214604.126 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214604.126 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=4236530a4e4f5c64 ms=500
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: presence

Content-Length: 0




DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.594 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.626 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=4236530a4e4f5c64 ms=1000
DEBUG | 20080721-214604.626 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.259 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214605.259 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869

Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=4236530a4e4f5c64 ms=5000
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:66 | Checking if SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire) is for me
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:71 | Checking rule...
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | MessageFilterRule.cxx:42 | Matching rule for:

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | TransactionUser.cxx:74 | Match!
DEBUG | 20080721-214605.271 | Mytest | RESIP | 3516 | Helper.cxx:372 | Helper::makeResponse(SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire) code=100 reason=
DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer Trying tid=05d2.26e69a61.0 ms=3500
DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=1

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




INFO | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 202 tid=4236530a4e4f5c64 cseq=SUBSCRIBE contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 202 tid=4236530a4e4f5c64 cseq=SUBSCRIBE contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801to cached
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from current to cached
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:770 | mState == 3 Creating a new Dialog from msg:

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869

Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:220 | ************** Created Dialog as UAC **************
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:221 | mRemoteNameAddr: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:222 | mLocalNameAddr: <sip:testuser1@xxxxxxxxx>;tag=5d10f801
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:223 | mLocalContact: <sip:testuser1>
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:224 | mRemoteTarget: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:227 | Dialog::Dialog ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:808 | ### Calling CreateAppDialog ###:

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869

Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:328 | Dialog::dispatch: SipResp: 202 tid=4236530a4e4f5c64 cseq=SUBSCRIBE contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | KeepAliveManager.cxx:40 | Association added for [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1617 | DialogUsageManager::processRequest: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
INFO | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1677 | Handling in-dialog request: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:467 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:328 | Dialog::dispatch: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:517 | Making subscription (from creator) request: SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: presence

Content-Length: 0




DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:33 | ClientSubscription::ClientSubscription from SipReq:  SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=4236530a4e4f5c64 cseq=SUBSCRIBE contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:962 | Dialog::makeRequest:

SUBSCRIBE sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-97021349f01e6101-1---d8754z-;rport

Max-Forwards: 70

Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 3 SUBSCRIBE

Event: presence

Content-Length: 0




DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:59 | ClientSubscription::dispatch SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
INFO | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:80 | [ClientSubscription] <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67
DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:103 | no queued notify
DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:282 | No expires header in last request, set to 3600
DEBUG | 20080721-214605.278 | Mytest | RESIP:TRANSACTION | 3536 | TimerQueue.cxx:105 | Adding application timer: DumTimeout::Subscription ClientSubscription sip:testuser1@xxxxxxxxx: duration=3240 seq=1
INFO | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | ClientSubscription.cxx:353 | [ClientSubscription] reSUBSCRIBE in 3240
DEBUG | 20080721-214605.279 | Mytest | RESIP | 3536 | Helper.cxx:372 | Helper::makeResponse(SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire) code=400 reason=
DEBUG | 20080721-214605.279 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:1025 | Dialog::makeResponse:

SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

Content-Length: 0




DEBUG | 20080721-214605.279 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214605.279 | Mytest | RESIP | 3536 | SipStack.cxx:289 | SEND: SipResp: 400 tid=05d2.26e69a61.0 cseq=NOTIFY / 2 from(tu)
DEBUG | 20080721-214605.296 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer J tid=05d2.26e69a61.0 ms=32000
DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=unspecified mFlowKey=660 ]

SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.735 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.735 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.936 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214605.936 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214609.936 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214609.936 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214610.450 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.450 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214610.450 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214610.450 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7", stale=true

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.de44

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser1@xxxxxxxxx out_uri=sip:testuser1@xxxxxxxxx via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214610.451 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer K tid=3f10b27dee371b0d ms=5000
INFO | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=3f10b27dee371b0d cseq=SUBSCRIBE / 2 from(wire)
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=3f10b27dee371b0d cseq=SUBSCRIBE / 2 from(wire)
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04BA06F0 Digest realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true is proxy: 1
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:236 | Stale nonce:Digest realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:63 | Produced response to digest challenge for UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | about to re-send request with digest credentials
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:319 |  Add auth, 04BA06F0 in response to: Digest realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, proxy: 1 Digest username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5
DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 3 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=b133ac649443aa33 cseq=SUBSCRIBE contact=testuser1 / 3 from(tu)
DEBUG | 20080721-214610.476 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=b133ac649443aa33 ms=32000
DEBUG | 20080721-214610.476 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214610.476 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214610.476 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=b133ac649443aa33 ms=500
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 3 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
INFO | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | ClientRegistration.cxx:189 | Removing binding
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | ClientAuthManager.cxx:319 |  Add auth, 04B87168 in response to: Digest realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891"
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, proxy: 0 Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-e850ac775b4aee0c-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>;expires=0

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 3 REGISTER

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214610.772 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214610.772 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> SipReq:  REGISTER iptel.org tid=e850ac775b4aee0c cseq=REGISTER contact=testuser1 / 3 from(tu)
DEBUG | 20080721-214610.780 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer F tid=e850ac775b4aee0c ms=32000
DEBUG | 20080721-214610.780 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214610.780 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214610.780 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=e850ac775b4aee0c ms=500
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:1211 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.784 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 192.168.1.128:5832;branch=z9hG4bK-d8754z-e850ac775b4aee0c-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>;expires=0

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 3 REGISTER

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214610.784 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
WARNING | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:243 | force shutdown
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:93 | Waiting for usages to be deleted (6)
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 1 -> AppDialogSet 1
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 3 -> AppDialogSet 3
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 5 -> AppDialog 5
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 2 -> ClientRegistration sip:testuser1@xxxxxxxxx
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 4 -> AppDialogSet 4
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 | 6 -> ClientSubscription sip:testuser1@xxxxxxxxx
INFO | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:207 | DialogUsageManager::onAllHandlesDestroyed: removing TU
DEBUG | 20080721-214610.976 | Mytest | RESIP:TRANSACTION | 3516 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=b133ac649443aa33 ms=1000
DEBUG | 20080721-214610.976 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ]
INFO | 20080721-214610.976 | Mytest | RESIP:TRANSACTION | 3516 | TuSelector.cxx:40 | TransactionUserMessage::RemoveTransactionUser TU: DialogUsageManager size=0
DEBUG | 20080721-214610.994 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214610.994 | Mytest | RESIP:TRANSACTION | 3516 | TransactionState.cxx:366 | No TU found for message: SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=701.ee5d38a3.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 3 from(wire)
DEBUG | 20080721-214610.994 | Mytest | RESIP | 3516 | Helper.cxx:372 | Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=701.ee5d38a3.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 3 from(wire) code=500 reason=
DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:559 | Looked up source for destination: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP target domain=unspecified mFlowKey=660 ]

SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK701.ee5d38a3.1

Via: SIP/2.0/UDP 192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=ab586811

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 3 SUBSCRIBE

Content-Length: 0




DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214611.172 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:1107 | TU unregistered
WARNING | 20080721-214611.174 | Mytest | RESIP | 3516 | StackThread.cxx:45 | Shutting down stack thread
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:130 | DialogUsageManager::mDialogSetMap has 3 DialogSets
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:134 | DialgSetId:Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:134 | DialgSetId:ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:139 | DialogId:ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67, mClientSubscriptions(1), mServerSubscriptions(0)
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:134 | DialgSetId:ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | ClientRegistration.cxx:59 | ClientRegistration::~ClientRegistration
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | KeepAliveManager.cxx:49 | Association removed for [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:1007 | DialogUsageManager::destroy() not posting to stack
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:122 |  ********** DialogSet::~DialogSet: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334*************
DEBUG | 20080721-214611.793 | Mytest | RESIP:DUM | 1292 | Dialog.cxx:232 | Dialog::~Dialog()
INFO | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | DialogUsageManager.cxx:1007 | DialogUsageManager::destroy() not posting to stack
DEBUG | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | KeepAliveManager.cxx:49 | Association removed for [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | KeepAliveManager.cxx:53 | Keepalive 0 removed
DEBUG | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | KeepAliveManager.cxx:54 | No more association for [ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:122 |  ********** DialogSet::~DialogSet: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801*************
DEBUG | 20080721-214611.794 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:122 |  ********** DialogSet::~DialogSet: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b*************
DEBUG | 20080721-214611.794 | Mytest | RESIP | 1292 | SipStack.cxx:83 | SipStack::~SipStack()
DEBUG | 20080721-214611.795 | Mytest | RESIP:TRANSACTION | 1292 | TransactionMap.cxx:18 | 05d2.26e69a61.0 -> 04B9C548: tid=05d2.26e69a61.0 [ ServerNonInvite/Completed unreliable target=[ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]]
DEBUG | 20080721-214611.795 | Mytest | RESIP:TRANSACTION | 1292 | TransactionMap.cxx:18 | 601.ea9a0476.1 -> 04B921F8: tid=601.ea9a0476.1 [ ServerNonInvite/Completed unreliable target=[ V4 213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ]]
DEBUG | 20080721-214611.795 | Mytest | RESIP:TRANSACTION | 1292 | TransactionMap.cxx:18 | 3f10b27dee371b0d -> 04B92A60: tid=3f10b27dee371b0d [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
DEBUG | 20080721-214611.795 | Mytest | RESIP:TRANSACTION | 1292 | TransactionMap.cxx:18 | b133ac649443aa33 -> 04B87278: tid=b133ac649443aa33 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
DEBUG | 20080721-214611.796 | Mytest | RESIP:TRANSACTION | 1292 | TransactionMap.cxx:18 | e850ac775b4aee0c -> 04B82650: tid=e850ac775b4aee0c [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
INFO | 20080721-214611.796 | Mytest | RESIP:TRANSPORT | 1292 | UdpTransport.cxx:66 | Shutting down [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]


On Mon, Jul 21, 2008 at 8:50 PM, Scott Godin <slgodin@xxxxxxxxxxxx> wrote:

You are probably missing some configuration (ie. mDum.addClientSubscriptionHandler("presence", mHanlder)).  You should examine the resip logs, or step through the code in a debugger  in order to figure this out.  You may also find dum/test/UserAgent.cxx is good reference.

 

Scott

 

From: resiprocate-users-bounces@xxxxxxxxxxxxxxx [mailto:resiprocate-users-bounces@xxxxxxxxxxxxxxx] On Behalf Of Karlsson
Sent: Monday, July 21, 2008 4:08 AM
To: resiprocate-users@xxxxxxxxxxxxxxx
Subject: [reSIProcate-users] The Dum says 400 Bad request when received aNOTIFY message

 

Hi all, I try to implement the PRESENCE by reSIProcate - dum, but I send SUBSCRIBE to server, server replied me 202 accepted,
and it send NOTIFY to me later, but the DUM replied "400 Bad request " when it received the NOTIFY, how to do  ?


I have wrote these code:

    mMasterProfile->addSupportedMethod(NOTIFY);
    mMasterProfile->addSupportedMimeType(NOTIFY, Mime("message", "sipfrag"));
    mMasterProfile->addSupportedMimeType(NOTIFY, Mime("application", "simple-message-summary"));
    mMasterProfile->addSupportedMimeType(NOTIFY, Pidf::getStaticType());
    mMasterProfile->addSupportedMimeType(MESSAGE, Mime("text", "plain"));


There is the logs:


SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:testuser1@xxxxxxxxxxxxx:6684>
To: <sip:testuser2@xxxxxxxxx:5060>
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO
Proxy-Authorization: Digest

username="testuser1",realm="iptel.org",nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6",uri="sip:testuser2@xxxxxxxxx:5060",response="a3ed7c2d5c27de671c8c5e7c4bc1a84

3",algorithm=MD5
Supported: replaces
User-Agent: TEST PHONE
Event: presence
Content-Length: 0



SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-6a7fdd0750244763-1---d8754z-;rport=2591;received=222.244.116.11
To: <sip:testuser2@xxxxxxxxx:5060>;tag=b98f1230ac1a7949bf499aa7f9ea7038.49b5
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 1 SUBSCRIBE
Proxy-Authenticate: Digest realm="iptel.org", nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6"
Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))
Content-Length: 0
Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 req_src_ip=222.244.116.11 req_src_port=2591 in_uri=sip:testuser2@xxxxxxxxx:5060

out_uri=sip:testuser2@xxxxxxxxx:5060 via_cnt==1"




SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:testuser1@xxxxxxxxxxxxx:6684>
To: <sip:testuser2@xxxxxxxxx:5060>
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO
Proxy-Authorization: Digest

username="testuser1",realm="iptel.org",nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6",uri="sip:testuser2@xxxxxxxxx:5060",response="a3ed7c2d5c27de671c8c5e7c4bc1a84

3",algorithm=MD5
Supported: replaces
User-Agent: TEST PHONE
Event: presence
Content-Length: 0



SIP/2.0 202 Accepted
Via: SIP/2.0/UDP 192.168.1.128:6684;received=222.244.116.11;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport=2591
Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
User-Agent: Bria release 2.3 stamp 47296
Content-Length: 0




NOTIFY sip:testuser1@xxxxxxxxxxxxxx:2591 SIP/2.0
Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
Max-Forwards: 16
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: Bria release 2.3 stamp 47296
Subscription-State: pending;expires=3600
Event: presence
Content-Length: 0
P-hint: rr-enforced



SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: TEST PHONE
Content-Length: 0


NOTIFY sip:testuser1@xxxxxxxxxxxxxx:2591 SIP/2.0
Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
Max-Forwards: 16
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: Bria release 2.3 stamp 47296
Subscription-State: pending;expires=3600
Event: presence
Content-Length: 0
P-hint: rr-enforced



SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: TEST PHONE
Content-Length: 0