Elwin Formsma
2012-11-19 13:15:30 UTC
Hi,
We have a Sipxecs instance where the proxy on server2 crashes every few days. The sipXproxy takes all CPU and stops responding.
Below is a small part of the proxy log on NOTICE level. These messages are found all through the proxy log. Anyone has a clue what this could be or is more logging required?
Kind regards,
Met vriendelijke groet,
Elwin Formsma
Telecats BV
-----
Elwin Formsma | Telecats bv | KvK Enschede 06069106 | Tel: 053 488 99 44 | Fax: 053 488 99 10 | E-mail: ***@telecats.nl |
"2012-11-19T13:05:46.293713Z":150678:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.294482Z":150679:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send response without an existing transaction"
"2012-11-19T13:05:46.294498Z":150680:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send failed to construct new transaction"
"2012-11-19T13:05:46.294507Z":150681:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.299424Z":150682:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.299789Z":150683:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nSUBSCRIBE sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat SIP/2.0\r\nRecord-Route: <sip:10.110.3.2:5060;lr;sipXecs-rs=%2Aauth%7E.%2Afrom%7EWW9kSEZ0%218ff9a8824a50ed542c85198dc8369203>\r\nFrom: <sip:***@10.110.3.1:57027>;tag=YodHFt\r\nTo: <sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat>\r\nCall-Id: ojp0rN1X_jf62b\r\nCseq: 2 SUBSCRIBE\r\nContact: <sip:10.110.3.1:57027;transport=udp;x-sipX-nonat>\r\nEvent: dialog\r\nAccept: application/dialog-info+xml\r\nExpires: 2841\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/rls (Linux)\r\nAccept-Language: en\r\nP
roxy-Authorization: Digest username=\"~~id~sipXrls\", realm=\"voip.ede.nl\", nonce=\"0e7781229ee3463798e02fd539cebc9150aa2e93\", uri=\"sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat
\", response=\"b97d3f9563785402dc4049dec915e0d7\", cnonce=\"QxygTI\", qop=auth, nc=00000001\r\nVia: SIP/2.0/UDP 10.110.3.1:57027;branch=z9hG4bK-XX-3f4570v6PzcQJ`g5QKzV_GSh9A\r\nContent-Length: 0\r\n\r\n\n SipTransaction dump:\n this: 0x2aaacbbc1fb0\n hash: ojp0rN1X_jf62bc2\n mCallId: ojp0rN1X_jf62b\n mpBranchId->data(): z9hG4bK-XX-1cc2yGi70UPYRz2P5ofgBHYhvg\n mRequestUri: sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.11.228\n mSendToPort: 5060\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRecords: NULL\n mFromField: <sip:***@10.110.3.1:57027>;tag=YodHFt\n mToField: <sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat>\n mRequestMethod: SUBSCRIBE\n mCseq: 2\n mIsServerTransaction: FALSE\n mIsUa
Transaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (nil)\n
mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.299878Z":150684:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.299952Z":150685:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaacbbc1fb0 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.299990Z":150686:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.300384Z":150687:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.302087Z":150688:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.302381Z":150689:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nNOTIFY sip:10.110.3.1:57027;transport=udp;x-sipX-nonat SIP/2.0\r\nFrom: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=gvQE8c\r\nTo: <sip:***@10.110.3.1:57027>;tag=6Ul9P8\r\nCseq: 71 NOTIFY\r\nCall-Id: QQT1LO_hhYaf09\r\nEvent: reg\r\nSubscription-State: active;expires=1898\r\nContent-Type: application/reginfo+xml\r\nContact: <sip:***@10.110.3.2:5075;x-sipX-nonat>\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/reg-event (Linux)\r\nAccept-Language: en\r\nVia: SIP/2.0/TCP 10.110.3.2:5075;branch=z9hG4bK-XX-818cO5afILl3lz4Ogh3gCmCKsg\r\nConten
t-Length: 746\r\n\r\n<?xml version=\"1.0\"?>\r\n<reginfo xmlns=\"urn:ietf:params:xml:ns:reginfo\" xmlns:gr=\"urn:ietf:params:xml:ns:gruuinfo\" xmlns:in=\"http://www.sipfoundry.org/sipX/schem
a/xml/reg-instrument-00-00\" version=\"70\" state=\"full\">\r\n <registration aor=\"sip:***@voip.ede.nl\" id=\"sip:***@voip.ede.nl\" state=\"active\">\r\n <contact id=\"sip:***@voip.ede.nl@@"Henny Pieters"<sip:***@10.110.6.175:5060;transport=udp;x-sipX-nonat>\" state=\"active\" event=\"registered\" q=\"1\" callid=\"69de564fdb53eb42\" cseq=\"5102\">\r\n <uri>sip:***@10.110.6.175:5060;transport=udp;x-sipX-nonat</uri>\r\n <display-name>"Henny Pieters"</display-name>\r\n <in:instrument>00085d2f5e39</in:instrument>\r\n </contact>\r\n </registration>\r\n</reginfo>\r\n\n SipTransaction dump:\n this: 0x2aaaada73550\n hash: QQT1LO_hhYaf09c71\n mCallId: QQT1LO_hhYaf09\n mpBranchId->data(): z9hG4bK-XX-1cc3Uc4YE1Qkvi`y6DGU3`
7nNw\n mRequestUri: sip:10.110.3.1:57027;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.3.1\n mSendToPort: 57027\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRec
ords: NULL\n mFromField: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=gvQE8c\n mToField: <sip:***@10.110.3.1:57027>;tag=6Ul9P8\n mRequestMethod: NOTIFY\n mCseq: 71\n mIsServerTransaction: FALSE\n mIsUaTransaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (nil)\n mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1
.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.302448Z":150690:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.302485Z":150691:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaaada73550 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.302504Z":150692:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.304986Z":150693:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.305236Z":150694:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nNOTIFY sip:10.110.3.1:57027;transport=udp;x-sipX-nonat SIP/2.0\r\nFrom: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=rol_gE\r\nTo: <sip:***@10.110.3.1:57027>;tag=wCzNJH\r\nCseq: 11 NOTIFY\r\nCall-Id: pUTYUNfZmLd4ae\r\nEvent: reg\r\nSubscription-State: active;expires=1687\r\nContent-Type: application/reginfo+xml\r\nContact: <sip:***@10.110.3.2:5075;x-sipX-nonat>\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/reg-event (Linux)\r\nAccept-Language: en\r\nVia: SIP/2.0/UDP 10.110.3.2:5075;branch=z9hG4bK-XX-818fzGYUes0MacioXTrl9RjM4A\r\nConten
t-Length: 333\r\n\r\n<?xml version=\"1.0\"?>\r\n<reginfo xmlns=\"urn:ietf:params:xml:ns:reginfo\" xmlns:gr=\"urn:ietf:params:xml:ns:gruuinfo\" xmlns:in=\"http://www.sipfoundry.org/sipX/schem
a/xml/reg-instrument-00-00\" version=\"10\" state=\"full\">\r\n <registration aor=\"sip:***@voip.ede.nl\" id=\"sip:***@voip.ede.nl\" state=\"init\">\r\n </registration>\r\n</reginfo>\r\n\n SipTransaction dump:\n this: 0x2aaad4aad470\n hash: pUTYUNfZmLd4aec11\n mCallId: pUTYUNfZmLd4ae\n mpBranchId->data(): z9hG4bK-XX-1cc45H9nWT8pF20zT9wkSMrptw\n mRequestUri: sip:10.110.3.1:57027;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.3.1\n mSendToPort: 57027\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRecords: NULL\n mFromField: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=rol_gE\n mToField: <sip:***@10.110.3.1:57027>;tag=wCzNJH\n mRequestMethod: NOTIFY\n mCseq: 11\n mIsServerTransaction: FALSE\
n mIsUaTransaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (
nil)\n mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.305289Z":150695:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.305317Z":150696:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaad4aad470 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.305336Z":150697:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.309507Z":150698:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.317159Z":150699:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.328805Z":150700:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.331248Z":150701:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.336257Z":150702:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
We have a Sipxecs instance where the proxy on server2 crashes every few days. The sipXproxy takes all CPU and stops responding.
Below is a small part of the proxy log on NOTICE level. These messages are found all through the proxy log. Anyone has a clue what this could be or is more logging required?
Kind regards,
Met vriendelijke groet,
Elwin Formsma
Telecats BV
-----
Elwin Formsma | Telecats bv | KvK Enschede 06069106 | Tel: 053 488 99 44 | Fax: 053 488 99 10 | E-mail: ***@telecats.nl |
"2012-11-19T13:05:46.293713Z":150678:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.294482Z":150679:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send response without an existing transaction"
"2012-11-19T13:05:46.294498Z":150680:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send failed to construct new transaction"
"2012-11-19T13:05:46.294507Z":150681:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.299424Z":150682:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.299789Z":150683:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nSUBSCRIBE sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat SIP/2.0\r\nRecord-Route: <sip:10.110.3.2:5060;lr;sipXecs-rs=%2Aauth%7E.%2Afrom%7EWW9kSEZ0%218ff9a8824a50ed542c85198dc8369203>\r\nFrom: <sip:***@10.110.3.1:57027>;tag=YodHFt\r\nTo: <sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat>\r\nCall-Id: ojp0rN1X_jf62b\r\nCseq: 2 SUBSCRIBE\r\nContact: <sip:10.110.3.1:57027;transport=udp;x-sipX-nonat>\r\nEvent: dialog\r\nAccept: application/dialog-info+xml\r\nExpires: 2841\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/rls (Linux)\r\nAccept-Language: en\r\nP
roxy-Authorization: Digest username=\"~~id~sipXrls\", realm=\"voip.ede.nl\", nonce=\"0e7781229ee3463798e02fd539cebc9150aa2e93\", uri=\"sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat
\", response=\"b97d3f9563785402dc4049dec915e0d7\", cnonce=\"QxygTI\", qop=auth, nc=00000001\r\nVia: SIP/2.0/UDP 10.110.3.1:57027;branch=z9hG4bK-XX-3f4570v6PzcQJ`g5QKzV_GSh9A\r\nContent-Length: 0\r\n\r\n\n SipTransaction dump:\n this: 0x2aaacbbc1fb0\n hash: ojp0rN1X_jf62bc2\n mCallId: ojp0rN1X_jf62b\n mpBranchId->data(): z9hG4bK-XX-1cc2yGi70UPYRz2P5ofgBHYhvg\n mRequestUri: sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.11.228\n mSendToPort: 5060\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRecords: NULL\n mFromField: <sip:***@10.110.3.1:57027>;tag=YodHFt\n mToField: <sip:***@10.110.11.228:5060;transport=udp;x-sipX-nonat>\n mRequestMethod: SUBSCRIBE\n mCseq: 2\n mIsServerTransaction: FALSE\n mIsUa
Transaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (nil)\n
mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.299878Z":150684:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.299952Z":150685:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaacbbc1fb0 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.299990Z":150686:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.300384Z":150687:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.302087Z":150688:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.302381Z":150689:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nNOTIFY sip:10.110.3.1:57027;transport=udp;x-sipX-nonat SIP/2.0\r\nFrom: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=gvQE8c\r\nTo: <sip:***@10.110.3.1:57027>;tag=6Ul9P8\r\nCseq: 71 NOTIFY\r\nCall-Id: QQT1LO_hhYaf09\r\nEvent: reg\r\nSubscription-State: active;expires=1898\r\nContent-Type: application/reginfo+xml\r\nContact: <sip:***@10.110.3.2:5075;x-sipX-nonat>\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/reg-event (Linux)\r\nAccept-Language: en\r\nVia: SIP/2.0/TCP 10.110.3.2:5075;branch=z9hG4bK-XX-818cO5afILl3lz4Ogh3gCmCKsg\r\nConten
t-Length: 746\r\n\r\n<?xml version=\"1.0\"?>\r\n<reginfo xmlns=\"urn:ietf:params:xml:ns:reginfo\" xmlns:gr=\"urn:ietf:params:xml:ns:gruuinfo\" xmlns:in=\"http://www.sipfoundry.org/sipX/schem
a/xml/reg-instrument-00-00\" version=\"70\" state=\"full\">\r\n <registration aor=\"sip:***@voip.ede.nl\" id=\"sip:***@voip.ede.nl\" state=\"active\">\r\n <contact id=\"sip:***@voip.ede.nl@@"Henny Pieters"<sip:***@10.110.6.175:5060;transport=udp;x-sipX-nonat>\" state=\"active\" event=\"registered\" q=\"1\" callid=\"69de564fdb53eb42\" cseq=\"5102\">\r\n <uri>sip:***@10.110.6.175:5060;transport=udp;x-sipX-nonat</uri>\r\n <display-name>"Henny Pieters"</display-name>\r\n <in:instrument>00085d2f5e39</in:instrument>\r\n </contact>\r\n </registration>\r\n</reginfo>\r\n\n SipTransaction dump:\n this: 0x2aaaada73550\n hash: QQT1LO_hhYaf09c71\n mCallId: QQT1LO_hhYaf09\n mpBranchId->data(): z9hG4bK-XX-1cc3Uc4YE1Qkvi`y6DGU3`
7nNw\n mRequestUri: sip:10.110.3.1:57027;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.3.1\n mSendToPort: 57027\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRec
ords: NULL\n mFromField: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=gvQE8c\n mToField: <sip:***@10.110.3.1:57027>;tag=6Ul9P8\n mRequestMethod: NOTIFY\n mCseq: 71\n mIsServerTransaction: FALSE\n mIsUaTransaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (nil)\n mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1
.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.302448Z":150690:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.302485Z":150691:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaaada73550 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.302504Z":150692:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.304986Z":150693:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent[SipUserAgent-2]::send proxied client transaction does not have parent"
"2012-11-19T13:05:46.305236Z":150694:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::handleOutgoing invalid relationship: DIFFERENT_BRANCH\nNOTIFY sip:10.110.3.1:57027;transport=udp;x-sipX-nonat SIP/2.0\r\nFrom: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=rol_gE\r\nTo: <sip:***@10.110.3.1:57027>;tag=wCzNJH\r\nCseq: 11 NOTIFY\r\nCall-Id: pUTYUNfZmLd4ae\r\nEvent: reg\r\nSubscription-State: active;expires=1687\r\nContent-Type: application/reginfo+xml\r\nContact: <sip:***@10.110.3.2:5075;x-sipX-nonat>\r\nDate: Mon, 19 Nov 2012 13:05:23 GMT\r\nMax-Forwards: 19\r\nUser-Agent: sipXecs/4.4.0 sipXecs/reg-event (Linux)\r\nAccept-Language: en\r\nVia: SIP/2.0/UDP 10.110.3.2:5075;branch=z9hG4bK-XX-818fzGYUes0MacioXTrl9RjM4A\r\nConten
t-Length: 333\r\n\r\n<?xml version=\"1.0\"?>\r\n<reginfo xmlns=\"urn:ietf:params:xml:ns:reginfo\" xmlns:gr=\"urn:ietf:params:xml:ns:gruuinfo\" xmlns:in=\"http://www.sipfoundry.org/sipX/schem
a/xml/reg-instrument-00-00\" version=\"10\" state=\"full\">\r\n <registration aor=\"sip:***@voip.ede.nl\" id=\"sip:***@voip.ede.nl\" state=\"init\">\r\n </registration>\r\n</reginfo>\r\n\n SipTransaction dump:\n this: 0x2aaad4aad470\n hash: pUTYUNfZmLd4aec11\n mCallId: pUTYUNfZmLd4ae\n mpBranchId->data(): z9hG4bK-XX-1cc45H9nWT8pF20zT9wkSMrptw\n mRequestUri: sip:10.110.3.1:57027;transport=udp;x-sipX-nonat\n mSendToAddress: 10.110.3.1\n mSendToPort: 57027\n mSendToProtocol: UDP\n mCancelReasonValue: \n mpDnsSrvRecords: NULL\n mFromField: <sip:***@voip.ede.nl;sipx-noroute=VoiceMail;sipx-userforward=false>;tag=rol_gE\n mToField: <sip:***@10.110.3.1:57027>;tag=wCzNJH\n mRequestMethod: NOTIFY\n mCseq: 11\n mIsServerTransaction: FALSE\
n mIsUaTransaction: FALSE\n mpRequest: (nil)\n mpLastProvisionalResponse: (nil)\n mpLastFinalResponse: (nil)\n mpAck: (nil)\n mpCancel: (nil)\n mpCancelResponse: (
nil)\n mpParentTransaction: (nil)\n mChildTransactions: none\n mTransactionCreateTime: 2220877\n mTransactionStartTime: -1\n mTimeStamp: 2220877\n mTransactionState: LOCALLY_INITIATED\n mIsCanceled: FALSE\n mIsRecursing: FALSE\n mIsDnsSrvChild: FALSE\n mProvisionalSdp: FALSE\n mProvoExtendsTimer: FALSE\n mQvalue: 1.000000\n mExpires: -1\n mIsBusy: 2220877\n mBusyTaskName: SipRouter-17\n mWaitingList: (nil)"
"2012-11-19T13:05:46.305289Z":150695:SIP:ERR:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::addResponse message with bad relationship: 3"
"2012-11-19T13:05:46.305317Z":150696:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipTransaction::recurseDnsSrvChildren Returning false: 0x2aaad4aad470 isrecursing False mIsServerTransaction = 0, mIsDnsSrvChild = 0, mpDnsDestinations = (nil), mpDnsDestinations[0].isValidServerT() = 0, mpRequest = (nil)"
"2012-11-19T13:05:46.305336Z":150697:SIP:WARNING:sipxecs2.voip.ede.nl:SipRouter-17:423B1940:SipXProxy:"SipUserAgent::send returning false"
"2012-11-19T13:05:46.309507Z":150698:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.317159Z":150699:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.328805Z":150700:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.331248Z":150701:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
"2012-11-19T13:05:46.336257Z":150702:SIP:ERR:sipxecs2.voip.ede.nl:SipUserAgent-2:40485940:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"