I turned on debug for sipregistrar and got some additional trace
information. Is there any place to look for a problem.
This worked yesterday with the same phone numbers, same ITSP, same code.
"2008-07-03T15:13:10.563143Z":514:SIP:DEBUG:sipx.spatialtour.com:SipUserAgent-5:B6DABB90:SipRegistrar:"SipUserAgent[SipUserAgent-5]::handleMessage
calling garbageCollection()"
"2008-07-03T15:13:10.574105Z":515:SIP:DEBUG:sipx.spatialtour.com:SipUserAgent-14:B68A6B90:SipRegistrar:"SipUserAgent[SipUserAgent-14]::handleMessage
calling garbageCollection()"
"2008-07-03T15:13:10.577094Z":516:SIP:DEBUG:sipx.spatialtour.com:SipUserAgent-21:B619FB90:SipRegistrar:"SipUserAgent[SipUserAgent-21]::handleMessage
calling garbageCollection()"
"2008-07-03T15:13:13.203484Z":517:HTTP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"HttpMessage::read
full msg rcvd bytes 1553: header: 1338 content: 215"
"2008-07-03T15:13:13.203920Z":518:INCOMING:INFO:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"Read
SIP message:\n----Remote Host:67.192.62.171---- Port: 35587----\nINVITE
sip:[EMAIL PROTECTED] SIP/2.0\r\nRoute:
<sip:67.192.62.171:5070;transport=tcp;lr>\r\nFrom: <sip:[EMAIL
PROTECTED]>;tag=5241ed42-13c4-486cec89-49242136-2229d63a\r\nTo: <sip:[EMAIL
PROTECTED]>\r\nCall-Id:
97a2223c985eadc713c4486cec88adaca4c163ae9a10a114a80-0084-4741\r\nCseq: 1
INVITE\r\nRecord-Route: <sip:67.192.62.171:5060;lr>\r\nRecord-Route:
<sip:66.237.65.67:5060;transport=UDP;lr;cxc-rr>\r\nVia: SIP/2.0/TCP
67.192.62.171;branch=z9hG4bK-sipXecs-000b6791987d6fa8d2ae40f841215b4c64ef\r\nVia:
SIP/2.0/UDP
66.237.65.67:5060;branch=z9hG4bK-d7821-486cec89-49242136-35f212eb\r\nMax-Forwards:
14\r\nUser-Agent: Sippy\r\nCisco-Guid:
3314544591-3611152162-1731023143-3010291882\r\nH323-Conf-Id:
3314544591-3611152162-1731023143-3010291882\r\nPortabilling-Notify:
aor=2067500001\r\nP-Asserted-Identity: <sip:[EMAIL PROTECTED]>\r\nPrivacy:
none\r\nP-Charge-Info: <sip:[EMAIL PROTECTED]>;npi=ISDN\r\nExpires:
300\r\nContact: <sip:[EMAIL PROTECTED]:5060;transport=UDP>\r\nRecord-Route:
<sip:10.1.2.1;lr;ftag=05b8f025c72b7e15d82fedb7fde8504b>\r\nVia: SIP/2.0/UDP
10.1.2.1;branch=z9hG4bKf9aa.4d30af809f606ce46865f0cea89f26f0.0\r\nVia:
SIP/2.0/UDP
10.1.2.1:5061;rport=5061;branch=z9hG4bKe51f13aa49ccab08bcc44af647e7a08f\r\nContent-Type:
application/sdp\r\nContent-Length: 215\r\nDate: Thu, 03 Jul 2008 15:13:13
GMT\r\nX-Sipx-Spiral: true\r\n\r\nv=0\r\no=Sippy 148569708 0 IN IP4
10.1.2.1\r\ns=-\r\np=+1 6135555555\r\nt=0 0\r\nm=audio 55662 RTP/AVP 18 0 8
101\r\nc=IN IP4 199.173.77.106\r\na=rtpmap:101
telephone-event/8000\r\na=fmtp:101 0-15\r\na=ptime:20\r\na=fmtp:18
annexb=no\r\n====================END====================\n"
"2008-07-03T15:13:13.204602Z":519:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipUserAgent[SipUserAgent-5]::dispatch
transaction = (nil), relationship = 0"
"2008-07-03T15:13:13.205120Z":520:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipTransaction::handleIncoming
0xb5c09d80 relationship REQUEST"
"2008-07-03T15:13:13.205956Z":521:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipClient[SipClientTcp-29]::isAcceptableForDestination
matches 67.192.62.171:5060 but is not trusted"
"2008-07-03T15:13:13.206005Z":522:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipProtocolServerBase[SipTcpServer-6]::send
SipClientTcp-30 (0x9688928), remote host: 67.192.62.171:5060 remote IP:
67.192.62.171:5060 remote Via address: :-1 received address: :-1"
"2008-07-03T15:13:13.206256Z":523:OUTGOING:INFO:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipUserAgent::sendTcp
TCP SIP User Agent sent message:\n----Remote Host:67.192.62.171---- Port:
5060----\nSIP/2.0 100 Trying\r\nF: <sip:[EMAIL
PROTECTED]>;tag=5241ed42-13c4-486cec89-49242136-2229d63a\r\nT: <sip:[EMAIL
PROTECTED]>\r\nI:
97a2223c985eadc713c4486cec88adaca4c163ae9a10a114a80-0084-4741\r\nCseq: 1
INVITE\r\nVia: SIP/2.0/TCP
67.192.62.171;branch=z9hG4bK-sipXecs-000b6791987d6fa8d2ae40f841215b4c64ef\r\nVia:
SIP/2.0/UDP
66.237.65.67:5060;branch=z9hG4bK-d7821-486cec89-49242136-35f212eb\r\nVia:
SIP/2.0/UDP
10.1.2.1;branch=z9hG4bKf9aa.4d30af809f606ce46865f0cea89f26f0.0\r\nVia:
SIP/2.0/UDP
10.1.2.1:5061;rport=5061;branch=z9hG4bKe51f13aa49ccab08bcc44af647e7a08f\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2008-07-03T15:13:13.206313Z":524:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipTransaction::handleChildIncoming
0xb5c09d80 relationship REQUEST parent (nil)"
"2008-07-03T15:13:13.206971Z":525:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-30:B5E9CB90:SipRegistrar:"SipClient[SipClientTcp-30]::run
got pipe-select OnErrWait-0 waitingToReport-0 mbOnErrWait-0 EOFs-0"
"2008-07-03T15:13:13.207047Z":526:SIP:DEBUG:sipx.spatialtour.com:SipRegistrar:B74B2B90:SipRegistrar:"SipRegistrar::handleMessage()
Start processing SIP message"
"2008-07-03T15:13:13.207398Z":527:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipRedirectServer::handleMessage
Start processing redirect message 0: 'INVITE' 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207489Z":528:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipRedirectServer::processRedirect
Starting to process request URI 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207539Z":529:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[110-REG]
SipRedirectorRegDB::lookUp gridPresent = 0, gridParameter = '', requestUriCopy
after removing grid = 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207574Z":530:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"RegistrationDB::getUnexpiredContacts
identity = '[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207665Z":531:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[110-REG]
SipRedirectorRegDB::lookUp got 0 unexpired contacts"
"2008-07-03T15:13:13.207711Z":532:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[120-ALIAS]
SipRedirectorAliasDB::lookUp identity '[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207784Z":533:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[130-SUBSCRIBE]
SipRedirectorSubscribe::lookUp uri 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.207959Z":534:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"UrlMapping::doTransform
adding 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.208074Z":535:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[140-MAPPING]
SipRedirectorMapping::lookUp got 0 UrlMapping Permission requirements for 1
contacts"
"2008-07-03T15:13:13.208106Z":536:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[140-MAPPING]
SipRedirectorMapping::lookUp got 1 UrlMapping Contacts"
"2008-07-03T15:13:13.208176Z":537:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[140-MAPPING]
SipRedirectorMapping::lookUp contact = 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.208242Z":538:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[140-MAPPING]
SipRedirectorMapping::lookUp contactUri = 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.208295Z":539:SIP:INFO:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"RedirectPlugin::addContact
Redirector '[140-MAPPING] SipRedirectorMapping' maps 'sip:[EMAIL PROTECTED]'
to 'sip:[EMAIL PROTECTED]'"
"2008-07-03T15:13:13.208474Z":540:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[150-FALLBACK]
SipRedirectorMapping::lookUp got 0 UrlMapping Permission requirements for 0
contacts"
"2008-07-03T15:13:13.208505Z":541:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[150-FALLBACK]
SipRedirectorMapping::lookUp got 0 UrlMapping Contacts"
"2008-07-03T15:13:13.208609Z":542:PARK:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"OrbitFileReader::findInOrbitList
user = '03003037291456', ret = (nil)"
"2008-07-03T15:13:13.208646Z":543:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[900-TIMEOFDAY]
SipRedirectorTimeOfDay::processResponse 0 contacts found"
"2008-07-03T15:13:13.208678Z":544:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"[999-AUTHROUTER]
SipRedirectorAuthRouter::lookUp 'INVITE' request is not an INVITE or has no
response contacts (0) - ignored."
"2008-07-03T15:13:13.208708Z":545:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipRedirectServer::processRedirect
No contacts added, sending 404 response"
"2008-07-03T15:13:13.209386Z":546:SIP:WARNING:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipUserAgent::send
response without an existing transaction"
"2008-07-03T15:13:13.209412Z":547:SIP:ERR:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipUserAgent::send
failed to construct new transaction"
"2008-07-03T15:13:13.426947Z":548:HTTP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"HttpMessage::read
full msg rcvd bytes 392: header: 392 content: 0"
"2008-07-03T15:13:13.427061Z":549:INCOMING:INFO:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"Read
SIP message:\n----Remote Host:67.192.62.171---- Port: 35587----\nCANCEL
sip:[EMAIL PROTECTED] SIP/2.0\r\nFrom: <sip:[EMAIL
PROTECTED]>;tag=fb1470a-13c4-486cec7b-267651b-6776a65c\r\nTo: <sip:[EMAIL
PROTECTED]>\r\nCall-Id:
994634fc905eadc713c4486cec7acce118614c2db7a0a3b4e30-0067-4259\r\nCseq: 1
CANCEL\r\nMax-Forwards: 20\r\nVia: SIP/2.0/TCP
67.192.62.171;branch=z9hG4bK-sipXecs-0009d851fb032772229cd56c34618ae07ff0\r\nContent-Length:
0\r\n\r\n====================END====================\n"
"2008-07-03T15:13:13.427607Z":550:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipUserAgent[SipUserAgent-5]::dispatch
transaction = 0x968fc20, relationship = 8"
"2008-07-03T15:13:13.427635Z":551:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipTransaction::handleIncoming
0x968fc20 relationship CANCEL"
"2008-07-03T15:13:13.428030Z":552:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipClient[SipClientTcp-29]::isAcceptableForDestination
matches 67.192.62.171:5060 but is not trusted"
"2008-07-03T15:13:13.428077Z":553:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipProtocolServerBase[SipTcpServer-6]::send
SipClientTcp-30 (0x9688928), remote host: 67.192.62.171:5060 remote IP:
67.192.62.171:5060 remote Via address: :-1 received address: :-1"
"2008-07-03T15:13:13.428320Z":554:OUTGOING:INFO:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipUserAgent::sendTcp
TCP SIP User Agent sent message:\n----Remote Host:67.192.62.171---- Port:
5060----\nSIP/2.0 200 OK\r\nF: <sip:[EMAIL
PROTECTED]>;tag=fb1470a-13c4-486cec7b-267651b-6776a65c\r\nT: <sip:[EMAIL
PROTECTED]>;tag=456141d6\r\nI:
994634fc905eadc713c4486cec7acce118614c2db7a0a3b4e30-0067-4259\r\nCseq: 1
CANCEL\r\nVia: SIP/2.0/TCP
67.192.62.171;branch=z9hG4bK-sipXecs-0009d851fb032772229cd56c34618ae07ff0\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2008-07-03T15:13:13.428373Z":555:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-30:B5E9CB90:SipRegistrar:"SipClient[SipClientTcp-30]::run
got pipe-select OnErrWait-0 waitingToReport-0 mbOnErrWait-0 EOFs-0"
"2008-07-03T15:13:13.428376Z":556:SIP:DEBUG:sipx.spatialtour.com:SipClientTcp-29:B5F9DB90:SipRegistrar:"SipTransaction::handleChildIncoming
0x968fc20 relationship CANCEL parent (nil)"
"2008-07-03T15:13:13.428968Z":557:SIP:DEBUG:sipx.spatialtour.com:SipRegistrar:B74B2B90:SipRegistrar:"SipRegistrar::handleMessage()
Start processing SIP message"
"2008-07-03T15:13:13.429129Z":558:SIP:DEBUG:sipx.spatialtour.com:SipRedirectServer-13:B66A4B90:SipRegistrar:"SipRedirectServer::handleMessage
Start processing redirect message 0: 'CANCEL' 'sip:[EMAIL PROTECTED]'"
_______________________________________________
sipx-dev mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-dev
Unsubscribe: http://list.sipfoundry.org/mailman/listinfo/sipx-dev
