"2007-12-03T23:59:46.079786Z":121535:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:48.185553Z":121536:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-03T23:59:48.186021Z":121537:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.212---- Port: 5060----\nINVITE sip:203@domain;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCSeq: 1 INVITE\r\nCall-ID: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nMax-Forwards: 70\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\n\r\nv=0\r\no=- 1196726389 1196726389 IN IP4 1.2.3.212\r\ns=Polycom IP Phone\r\nc=IN IP4 1.2.3.212\r\nt=0 0\r\nm=audio 2228 RTP/AVP 0 8 18 101\r\na=sendrecv\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:101 telephone-event/8000\r\n====================END====================\n" "2007-12-03T23:59:48.188161Z":121538:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-03T23:59:48.188323Z":121539:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUDPServer::createClient client: 0x816d000 1.2.3.212:5060" "2007-12-03T23:59:48.188381Z":121540:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUDPServerBase::send 0x816d000 isInUseForWrite 255882, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-03T23:59:48.188609Z":121541:OUTGOING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSIP/2.0 100 Trying\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-03T23:59:48.188935Z":121542:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"INVITE sip:203@domain;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nMax-Forwards: 70\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\n\r\nv=0\r\no=- 1196726389 1196726389 IN IP4 1.2.3.212\r\ns=Polycom IP Phone\r\nc=IN IP4 1.2.3.212\r\nt=0 0\r\nm=audio 2228 RTP/AVP 0 8 18 101\r\na=sendrecv\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:101 telephone-event/8000\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-03T23:59:48.189964Z":121543:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipRouter fowardingrules added route type 'local' to: ''" "2007-12-03T23:59:48.192086Z":121544:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers domain = '1.2.3.100', service = 'sip', socketType = UNKNOWN, port = 5070" "2007-12-03T23:59:48.192155Z":121545:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5070, weight = 0, score = 1000.000000, priority = 0, proto = UDP" "2007-12-03T23:59:48.192198Z":121546:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5070, weight = 0, score = 1000.000000, priority = 0, proto = TCP" "2007-12-03T23:59:48.195934Z":121547:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-03T23:59:48.196223Z":121548:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipUDPServer::createClient client: 0x8177400 1.2.3.100:5070" "2007-12-03T23:59:48.196285Z":121549:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipUDPServerBase::send 0x8177400 isInUseForWrite 255882, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-03T23:59:48.199055Z":121550:OUTGOING:INFO:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5070----\nINVITE sip:203@domain;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nMax-Forwards: 20\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\nRoute: \r\n\r\nv=0\r\no=- 1196726389 1196726389 IN IP4 1.2.3.212\r\ns=Polycom IP Phone\r\nc=IN IP4 1.2.3.212\r\nt=0 0\r\nm=audio 2228 RTP/AVP 0 8 18 101\r\na=sendrecv\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:101 telephone-event/8000\r\n--------------------END--------------------\n" "2007-12-03T23:59:48.199566Z":121551:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipTransaction::doFirstSend 0x81ee000 setting timeout 4" "2007-12-03T23:59:48.202781Z":121552:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-03T23:59:48.203010Z":121553:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.100---- Port: 50447----\nSIP/2.0 100 Trying\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-03T23:59:48.203867Z":121554:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SIP/2.0 100 Trying\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nContent-Length: 0\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-03T23:59:48.211278Z":121555:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-03T23:59:48.211595Z":121556:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.100---- Port: 57803----\nSIP/2.0 302 Moved Temporarily\r\nContact: sip:203@1.2.3.212\r\nContact: ;q=0.1\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nUser-Agent: sipX/3.6.0 sipX/registry (FreeBSD)\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: gruu\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-03T23:59:48.212146Z":121557:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipMessage::setAckData inviteRequest->getRequestUri() = 'sip:203@domain;user=phone'" "2007-12-03T23:59:48.213165Z":121558:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-03T23:59:48.213330Z":121559:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUDPServer::createClient client: 0x81d3800 1.2.3.100:5070" "2007-12-03T23:59:48.213387Z":121560:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUDPServerBase::send 0x81d3800 isInUseForWrite 255882, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-03T23:59:48.213681Z":121561:OUTGOING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5070----\nACK sip:203@domain;user=phone SIP/2.0\r\nRoute: \r\nContact: sip:203@1.2.3.212\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 ACK\r\nMax-Forwards: 20\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-03T23:59:48.213948Z":121562:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipTransaction::handleChildIncoming 0x81c5000" "2007-12-03T23:59:48.216334Z":121563:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipSrvLookup::servers domain = '1.2.3.100', service = 'sip', socketType = TCP, port = 5080" "2007-12-03T23:59:48.216394Z":121564:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5080, weight = 0, score = 1000.000000, priority = 0, proto = TCP" "2007-12-03T23:59:48.220004Z":121565:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipTCPServerBase::send 0x80d5800 isInUseForWrite 255882, client info\n remote host: 1.2.3.100:5080\n remote IP: 1.2.3.100:5080\n remote Via address: :-1\n received address: :-1" "2007-12-03T23:59:48.221019Z":121566:OUTGOING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5080----\nINVITE sip:203@1.2.3.212 SIP/2.0\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-74ef373a4c74ee9f6c3061a9ea8b20c8\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nMax-Forwards: 19\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\n\r\nv=0\r\no=- 1196726389 1196726389 IN IP4 1.2.3.212\r\ns=Polycom IP Phone\r\nc=IN IP4 1.2.3.212\r\nt=0 0\r\nm=audio 2228 RTP/AVP 0 8 18 101\r\na=sendrecv\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:101 telephone-event/8000\r\n--------------------END--------------------\n" "2007-12-03T23:59:48.221548Z":121567:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipTransaction::doFirstSend 0x81f0000 setting timeout 180" "2007-12-03T23:59:48.222337Z":121568:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SIP/2.0 302 Moved Temporarily\r\nContact: sip:203@1.2.3.212\r\nContact: ;q=0.1\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.100;branch=z9hG4bK-7e1a6c7b67bea071cedc4a483a3f99b2\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nUser-Agent: sipX/3.6.0 sipX/registry (FreeBSD)\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: gruu\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-03T23:59:48.700170Z":121569:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2007-12-03T23:59:48.700219Z":121570:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81c5000" "2007-12-03T23:59:48.700980Z":121571:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:52.203033Z":121572:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81ee000" "2007-12-03T23:59:52.203077Z":121573:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81ee000 ignoring cancel of DNS SRV child" "2007-12-03T23:59:52.203114Z":121574:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81c5000" "2007-12-03T23:59:52.203638Z":121575:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:56.222984Z":121576:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81c5000" "2007-12-03T23:59:56.224388Z":121577:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipSrvLookup::servers domain = '1.2.3.100', service = 'sip', socketType = TCP, port = 5080" "2007-12-03T23:59:56.224451Z":121578:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5080, weight = 0, score = 1000.000000, priority = 0, proto = TCP" "2007-12-03T23:59:56.226082Z":121579:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTCPServerBase::send 0x80d5800 isInUseForWrite 255890, client info\n remote host: 1.2.3.100:5080\n remote IP: 1.2.3.100:5080\n remote Via address: :-1\n received address: :-1" "2007-12-03T23:59:56.226590Z":121580:OUTGOING:INFO:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5080----\nINVITE sip:203@1.2.3.100:5100;voicexml=https%3A%2F%2Flocalhost%3A8091%2Fcgi-bin%2Fvoicemail%2Fmediaserver.cgi%3Faction%3Ddeposit%26mailbox%3D203 SIP/2.0\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-c1a69a134e02d95674b52dd4c95bd0c0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nMax-Forwards: 19\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Mon, 03 Dec 2007 23:59:48 GMT\r\n\r\nv=0\r\no=- 1196726389 1196726389 IN IP4 1.2.3.212\r\ns=Polycom IP Phone\r\nc=IN IP4 1.2.3.212\r\nt=0 0\r\nm=audio 2228 RTP/AVP 0 8 18 101\r\na=sendrecv\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:101 telephone-event/8000\r\n--------------------END--------------------\n" "2007-12-03T23:59:56.227085Z":121581:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::doFirstSend 0x81f1000 setting timeout 180" "2007-12-03T23:59:56.228424Z":121582:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:56.756079Z":121583:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81c9000" "2007-12-03T23:59:56.756129Z":121584:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81cd000" "2007-12-03T23:59:56.756650Z":121585:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:56.786971Z":121586:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81db000" "2007-12-03T23:59:56.787025Z":121587:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81cd000" "2007-12-03T23:59:56.787547Z":121588:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-03T23:59:58.971997Z":121589:SIP:DEBUG:pbx.domain.com:SipClient-2990:08160000:SipProxy:"SipClient::run 0x80ce400 socket 0x80e0200 host: sock addr: 1.2.3.202 via addr: 1.2.3.202 rcv addr: 1.2.3.202 sock type: TCP read ready READY" "2007-12-03T23:59:58.972157Z":121590:SIP:ERR:pbx.domain.com:SipClient-2990:08160000:SipProxy:"HttpMessage::read Message has no Content-Length on unframed socket type: 0\n" "2007-12-03T23:59:58.972258Z":121591:INCOMING:INFO:pbx.domain.com:SipClient-2990:08160000:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.202---- Port: 1490----\n\r\n\r\n====================END====================\n" "2007-12-04T00:00:00.863934Z":121592:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-04T00:00:00.864269Z":121593:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCSeq: 32 SUBSCRIBE\r\nCall-ID: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"a2c6dd8916282267dd1757d21158d7931196726083\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"89320b3b2fd6bb3cdca6319837802257\", algorithm=MD5\r\nMax-Forwards: 70\r\nExpires: 3600\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:00.865679Z":121594:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCseq: 32 SUBSCRIBE\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"a2c6dd8916282267dd1757d21158d7931196726083\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"89320b3b2fd6bb3cdca6319837802257\", algorithm=MD5\r\nMax-Forwards: 70\r\nExpires: 3600\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:00.866749Z":121595:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipRouter fowardingrules added route type 'local' to: ''" "2007-12-04T00:00:00.869048Z":121596:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers domain = '1.2.3.100', service = 'sip', socketType = TCP, port = 5110" "2007-12-04T00:00:00.869321Z":121597:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5110, weight = 0, score = 1000.000000, priority = 0, proto = TCP" "2007-12-04T00:00:00.871331Z":121598:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipTCPServerBase::send 0x8156c00 isInUseForWrite 255894, client info\n remote host: 1.2.3.100:5110\n remote IP: 1.2.3.100:5110\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:00.873962Z":121599:OUTGOING:INFO:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5110----\nSUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-27e34faa154467a7c04486c8d6c97f60\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCseq: 32 SUBSCRIBE\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"a2c6dd8916282267dd1757d21158d7931196726083\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"89320b3b2fd6bb3cdca6319837802257\", algorithm=MD5\r\nMax-Forwards: 20\r\nExpires: 3600\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nRoute: \r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:00.876828Z":121600:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipTransaction::doFirstSend 0x81f4000 setting timeout 180" "2007-12-04T00:00:00.880982Z":121601:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipClient::run 0x80dd400 socket 0x8148400 host: sock addr: 1.2.3.100 via addr: rcv addr: 1.2.3.100 sock type: TCP read ready READY" "2007-12-04T00:00:00.881308Z":121602:INCOMING:INFO:pbx.domain.com:SipClient-37:08148600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.100---- Port: 61868----\nSIP/2.0 401 Unauthorized\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 32 SUBSCRIBE\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-27e34faa154467a7c04486c8d6c97f60\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nWww-Authenticate: Digest realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\"\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContact: sip:1.2.3.100:5110\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:00.881871Z":121603:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipTransaction::handleChildIncoming 0x81f7000" "2007-12-04T00:00:00.882170Z":121604:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipTransaction::handleChildIncoming: response 401 for \r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r" "2007-12-04T00:00:00.882969Z":121605:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-04T00:00:00.883136Z":121606:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUDPServer::createClient client: 0x80f9800 1.2.3.212:5060" "2007-12-04T00:00:00.883194Z":121607:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUDPServerBase::send 0x80f9800 isInUseForWrite 255894, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:00.883962Z":121608:OUTGOING:INFO:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 32 SUBSCRIBE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nWww-Authenticate: Digest realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\"\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContact: sip:1.2.3.100:5110\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:00.884687Z":121609:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SIP/2.0 401 Unauthorized\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 32 SUBSCRIBE\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-27e34faa154467a7c04486c8d6c97f60\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nWww-Authenticate: Digest realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\"\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContact: sip:1.2.3.100:5110\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:00.884924Z":121610:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SIP User agent delayed dispatch message:\nSIP/2.0 401 Unauthorized\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 32 SUBSCRIBE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK23b200d2F3E76BBB\r\nWww-Authenticate: Digest realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\"\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContact: sip:1.2.3.100:5110\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:00.946682Z":121611:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-04T00:00:00.947016Z":121612:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCSeq: 33 SUBSCRIBE\r\nCall-ID: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"f4e1616cdbf1920a0def1d43e29fe3f0\", algorithm=MD5\r\nMax-Forwards: 70\r\nExpires: 3600\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:00.948419Z":121613:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCseq: 33 SUBSCRIBE\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"f4e1616cdbf1920a0def1d43e29fe3f0\", algorithm=MD5\r\nMax-Forwards: 70\r\nExpires: 3600\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:00.949605Z":121614:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipRouter fowardingrules added route type 'local' to: ''" "2007-12-04T00:00:00.954121Z":121615:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers domain = '1.2.3.100', service = 'sip', socketType = TCP, port = 5110" "2007-12-04T00:00:00.954401Z":121616:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipSrvLookup::servers host = '1.2.3.100', IP addr = '1.2.3.100', port = 5110, weight = 0, score = 1000.000000, priority = 0, proto = TCP" "2007-12-04T00:00:00.955418Z":121617:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipTCPServerBase::send 0x8156c00 isInUseForWrite 255894, client info\n remote host: 1.2.3.100:5110\n remote IP: 1.2.3.100:5110\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:00.955777Z":121618:OUTGOING:INFO:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Remote Host:1.2.3.100---- Port: 5110----\nSUBSCRIBE sip:203@domain SIP/2.0\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-ba546f260fbaaa9925cb08fa6b8627bf\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCseq: 33 SUBSCRIBE\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nAccept: application/simple-message-summary\r\nAuthorization: Digest username=\"203\", realm=\"domain\", nonce=\"226960b4940dfb0b42fb19b2a8939b131196726400\", opaque=\"change4\", uri=\"sip:203@domain\", response=\"f4e1616cdbf1920a0def1d43e29fe3f0\", algorithm=MD5\r\nMax-Forwards: 20\r\nExpires: 3600\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:00 GMT\r\nRoute: \r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:00.956126Z":121619:SIP:DEBUG:pbx.domain.com:SipRouter-11:080AC400:SipProxy:"SipTransaction::doFirstSend 0x8200000 setting timeout 180" "2007-12-04T00:00:01.125156Z":121620:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipClient::run 0x80dd400 socket 0x8148400 host: sock addr: 1.2.3.100 via addr: rcv addr: 1.2.3.100 sock type: TCP read ready READY" "2007-12-04T00:00:01.125481Z":121621:INCOMING:INFO:pbx.domain.com:SipClient-37:08148600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.100---- Port: 61868----\nSIP/2.0 202 Accepted\r\nExpires: 3263\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 33 SUBSCRIBE\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-ba546f260fbaaa9925cb08fa6b8627bf\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nContact: sip:203@domain\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:01 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:01.126061Z":121622:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipTransaction::handleChildIncoming 0x81fe000 topmost parent dispatching 202" "2007-12-04T00:00:01.126670Z":121623:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-04T00:00:01.126829Z":121624:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUDPServer::createClient client: 0x827b000 1.2.3.212:5060" "2007-12-04T00:00:01.126887Z":121625:SIP:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUDPServerBase::send 0x827b000 isInUseForWrite 255895, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:01.127392Z":121626:OUTGOING:INFO:pbx.domain.com:SipClient-37:08148600:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSIP/2.0 202 Accepted\r\nExpires: 3263\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 33 SUBSCRIBE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nContact: sip:203@domain\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:01 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:01.127829Z":121627:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-37:08148600:SipProxy:"SIP/2.0 202 Accepted\r\nExpires: 3263\r\nFrom: \"Stuart Gemmell\" ;tag=62931973-C6B67F0A\r\nTo: ;tag=1030316714\r\nCall-Id: d546242f-b7730b1-a401d568@1.2.3.212\r\nCseq: 33 SUBSCRIBE\r\nVia: SIP/2.0/TCP 1.2.3.100;branch=z9hG4bK-ba546f260fbaaa9925cb08fa6b8627bf\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bKf5a3d3b45FF431BD\r\nContact: sip:203@domain\r\nUser-Agent: sipX/3.6.0 sipX/publisher (FreeBSD)\r\nDate: Tue, 04 Dec 2007 00:00:01 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:04.228913Z":121628:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81c5000" "2007-12-04T00:00:04.229452Z":121629:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleChildTimeoutEvent: response 408 for \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r" "2007-12-04T00:00:04.229947Z":121630:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-04T00:00:04.230109Z":121631:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServer::createClient client: 0x827b400 1.2.3.212:5060" "2007-12-04T00:00:04.230167Z":121632:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServerBase::send 0x827b400 isInUseForWrite 255898, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:04.230835Z":121633:OUTGOING:INFO:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSIP/2.0 408 Request timeout\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nServer: sipX/3.6.0 sipX/forkingproxy (FreeBSD)\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:04.231422Z":121634:INCOMING_PARSED:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SIP User agent delayed dispatch message:\nSIP/2.0 408 Request timeout\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nServer: sipX/3.6.0 sipX/forkingproxy (FreeBSD)\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:04.231976Z":121635:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:04.732163Z":121636:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"OsDatagramSocket::_ attempt (null):0" "2007-12-04T00:00:04.732338Z":121637:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServer::createClient client: 0x828c000 1.2.3.212:5060" "2007-12-04T00:00:04.732763Z":121638:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServerBase::send 0x828c000 isInUseForWrite 255898, client info\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1" "2007-12-04T00:00:04.733376Z":121639:OUTGOING:INFO:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::sendUdp resend 1 of UDP message\nUDP SIP User Agent sent message:\n----Remote Host:1.2.3.212---- Port: 5060----\nSIP/2.0 408 Request timeout\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nServer: sipX/3.6.0 sipX/forkingproxy (FreeBSD)\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2007-12-04T00:00:04.734157Z":121640:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:04.793955Z":121641:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81de000" "2007-12-04T00:00:04.794006Z":121642:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81cd000" "2007-12-04T00:00:04.794524Z":121643:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:05.296400Z":121644:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-04T00:00:05.296684Z":121645:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.212---- Port: 5060----\nACK sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCSeq: 1 ACK\r\nCall-ID: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nMax-Forwards: 70\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:05.298019Z":121646:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"ACK sip:203@domain SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 ACK\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nMax-Forwards: 70\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:05 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:05.338736Z":121647:SIP:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipClient::run 0x809f400 socket 0x8093c00 host: sock addr: 0.0.0.0 via addr: 1.2.3.100 rcv addr: 1.2.3.100 sock type: UDP read ready READY" "2007-12-04T00:00:05.339015Z":121648:INCOMING:INFO:pbx.domain.com:SipClient-9:0809B600:SipProxy:"Read SIP message:\n----Remote Host:1.2.3.212---- Port: 5060----\nACK sip:203@domain;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCSeq: 1 ACK\r\nCall-ID: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nMax-Forwards: 70\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2007-12-04T00:00:05.339964Z":121649:SIP:WARNING:pbx.domain.com:SipClient-9:0809B600:SipProxy:"SipTransaction::addResponse ACK already exists, IGNORED" "2007-12-04T00:00:05.340660Z":121650:INCOMING_PARSED:DEBUG:pbx.domain.com:SipClient-9:0809B600:SipProxy:"ACK sip:203@domain;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 1.2.3.212;branch=z9hG4bK41a627caCCB92E33\r\nFrom: \"Stuart Gemmell\" ;tag=7AC08E30-E857E477\r\nTo: \r\nCseq: 1 ACK\r\nCall-Id: 632da9ac-2d7af3ce-71a41335@1.2.3.212\r\nContact: \r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.3.0127\r\nMax-Forwards: 70\r\nContent-Length: 0\r\nDate: Tue, 04 Dec 2007 00:00:05 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2007-12-04T00:00:05.736458Z":121651:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:08.219835Z":121652:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleExpiresEvent 0x81c8000" "2007-12-04T00:00:08.219903Z":121653:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81c5000" "2007-12-04T00:00:08.220964Z":121654:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServer::Removing old client 0x80d5000:\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1\r" "2007-12-04T00:00:08.221023Z":121655:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServer::Removing old client 0x81a9c00:\n remote host: :0\n remote IP: 0.0.0.0:0\n remote Via address: :-1\n received address: :-1\r" "2007-12-04T00:00:08.221095Z":121656:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUDPServer::removeOldClients deleting 2 of 9 SipClients (0 busy)" "2007-12-04T00:00:08.221388Z":121657:KERNEL:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"OsTaskLinux::doLinuxTerminateTask, deleting task thread: 0, force = 0" "2007-12-04T00:00:08.222194Z":121658:KERNEL:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"OsTaskLinux::doLinuxTerminateTask, deleting task thread: 0, force = 0" "2007-12-04T00:00:08.222850Z":121659:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:08.877743Z":121660:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2007-12-04T00:00:08.877791Z":121661:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81f7000" "2007-12-04T00:00:08.878055Z":121662:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0" "2007-12-04T00:00:08.956820Z":121663:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2007-12-04T00:00:08.956867Z":121664:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x81fe000" "2007-12-04T00:00:08.957145Z":121665:SIP:DEBUG:pbx.domain.com:SipUserAgent-1:080AC000:SipProxy:"SipUserAgent::handleMessage after GC, queue size = 0"