"2008-02-19T17:21:41.992397Z":12:SIP:CRIT:pbx.smartt.com::00000000:SipProxy:"sigHandler: closing IMDB connections" "2008-02-19T17:21:42.002680Z":13:KERNEL:ERR:pbx.smartt.com:SipServerBroker-4:B7D8AB90:SipProxy:"OsServerSocket: accept(4) error: 22=Invalid argument" "2008-02-19T17:21:42.835409Z":2:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_LOG_CONSOLE : DISABLE" "2008-02-19T17:21:42.835443Z":3:SIP:INFO:pbx.smartt.com::00000000:SipProxy:">>>>>>>>>>>>>>>> Starting - version 3.8.1-011577 build 2008-01-04T18:54:37 duck.pingtel.com" "2008-02-19T17:21:42.835477Z":4:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_DOMAIN_NAME : 69.31.174.100" "2008-02-19T17:21:42.835540Z":5:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_UDP_PORT : 5060" "2008-02-19T17:21:42.835574Z":6:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_TCP_PORT : 5060" "2008-02-19T17:21:42.835605Z":7:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_TLS_PORT : 5061" "2008-02-19T17:21:42.835647Z":8:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_MAX_FORWARDS : 20" "2008-02-19T17:21:42.835686Z":9:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_USE_AUTH_SERVER : ENABLE" "2008-02-19T17:21:42.835718Z":10:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_AUTH_SERVER : " "2008-02-19T17:21:42.835754Z":11:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_DEFAULT_EXPIRES : 180" "2008-02-19T17:21:42.835782Z":12:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_DEFAULT_SERIAL_EXPIRES : 20" "2008-02-19T17:21:42.835813Z":13:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_STALE_TCP_TIMEOUT : 3600" "2008-02-19T17:21:42.835843Z":14:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_DNSSRV_MAX_DESTS : -1" "2008-02-19T17:21:42.835873Z":15:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_DNSSRV_TIMEOUT : -1" "2008-02-19T17:21:42.835906Z":16:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_HOST_ALIASES : 69.31.174.100:5060 pbx:5060 pbx.smartt.com:5060 smartt.com" "2008-02-19T17:21:42.835937Z":17:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE : DISABLE" "2008-02-19T17:21:42.835973Z":18:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE_DB : ENABLE" "2008-02-19T17:21:42.836004Z":19:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE_DB_HOST : localhost" "2008-02-19T17:21:42.836037Z":20:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE_DB_NAME : SIPXCDR" "2008-02-19T17:21:42.836072Z":21:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE_DB_USER : postgres" "2008-02-19T17:21:42.836105Z":22:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SIP_PROXY_CALL_STATE_DB_DRIVER : {PostgreSQL}" "2008-02-19T17:21:42.838115Z":23:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipUserAgent-1' created 0xbfff0088" "2008-02-19T17:21:42.838180Z":24:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'SipUserAgent-1' queue: 0xbfff01cc queue limit: 10000" "2008-02-19T17:21:42.838254Z":25:SIP:DEBUG:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::_ sipTcpPort = 5060, sipUdpPort = 5060, sipTlsPort = 5061" "2008-02-19T17:21:42.838299Z":26:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipTcpServer-2' created 0x974ea58" "2008-02-19T17:21:42.838354Z":27:SIP:DEBUG:pbx.smartt.com::00000000:SipProxy:"SipTcpServer::_ 'SipTcpServer-2' specific port 5060 szBindAddr = '(null)'" "2008-02-19T17:21:42.838387Z":28:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipTcpServerBrokerListener-3' created 0x9750288" "2008-02-19T17:21:42.838421Z":29:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'SipTcpServerBrokerListener-3' queue: 0x97503cc queue limit: 100" "2008-02-19T17:21:42.838507Z":30:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'SipTcpServerBrokerListener-3' this = 0x9750288, mTaskId = -1209943152" "2008-02-19T17:21:42.838616Z":31:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getAllLocalHostIps entry 0, interface 'eth0', address '69.31.174.100'" "2008-02-19T17:21:42.838676Z":32:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsServerSocket::_ queue=64 port=5060 bindaddr=69.31.174.100" "2008-02-19T17:21:42.838847Z":33:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getAllLocalHostIps entry 0, interface 'eth0', address '69.31.174.100'" "2008-02-19T17:21:42.838885Z":34:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getContactAdapterName found name eth0 for ip 69.31.174.100" "2008-02-19T17:21:42.838999Z":35:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipServerBroker-4' created 0x9750f78" "2008-02-19T17:21:42.839091Z":36:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'SipServerBroker-4' this = 0x9750f78, mTaskId = -1210995824" "2008-02-19T17:21:42.839172Z":37:KERNEL:WARNING:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux:start 'SipServerBroker-4' attempting to start but not in UNINITIALIZED or TERMINATED state (1)" "2008-02-19T17:21:42.839243Z":38:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipUdpServer-5' created 0x97514e8" "2008-02-19T17:21:42.839296Z":39:SIP:DEBUG:pbx.smartt.com::00000000:SipProxy:"SipUdpServer::_ port = 5060, bUseNextAvailablePort = 0, szBoundIp = '(null)'" "2008-02-19T17:21:42.839362Z":40:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getAllLocalHostIps entry 0, interface 'eth0', address '69.31.174.100'" "2008-02-19T17:21:42.839420Z":41:SIP:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsDatagramSocket::_ attempt [null]:0" "2008-02-19T17:21:42.839482Z":42:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsDatagramSocket::_ 5 ((null):0 69.31.174.100:5060) socket and bind succeeded" "2008-02-19T17:21:42.839538Z":43:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'OsStunAgentTask-6' created 0x9752648" "2008-02-19T17:21:42.839593Z":44:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'OsStunAgentTask-6' queue: 0x975278c queue limit: 100" "2008-02-19T17:21:42.839677Z":45:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'OsStunAgentTask-6' this = 0x9752648, mTaskId = -1212048496" "2008-02-19T17:21:42.839750Z":46:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getAllLocalHostIps entry 0, interface 'eth0', address '69.31.174.100'" "2008-02-19T17:21:42.839787Z":47:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getContactAdapterName found name eth0 for ip 69.31.174.100" "2008-02-19T17:21:42.839937Z":48:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipClient-7' created 0x9753328" "2008-02-19T17:21:42.840183Z":49:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipClient[SipClient-7]::_ created UDP unshared socket 5: host '' port 0" "2008-02-19T17:21:42.840288Z":50:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'SipClient-7' this = 0x9753328, mTaskId = -1213101168" "2008-02-19T17:21:42.840439Z":51:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"getAllLocalHostIps entry 0, interface 'eth0', address '69.31.174.100'" "2008-02-19T17:21:42.840761Z":52:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'OsTimer-8' created 0x9754598" "2008-02-19T17:21:42.840815Z":53:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'OsTimer-8' queue: 0x97546dc queue limit: 10000" "2008-02-19T17:21:42.892394Z":56:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'OsTimer-8' this = 0x9754598, mTaskId = -1214153840" "2008-02-19T17:21:42.892464Z":57:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTimerTask::getTimerTask OsTimerTask started" "2008-02-19T17:21:42.892559Z":58:SIP:DEBUG:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::setHostAliases('69.31.174.100:5060 pbx:5060 pbx.smartt.com:5060 smartt.com')" "2008-02-19T17:21:42.892764Z":59:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::setHostAliases adding '69.31.174.100:5060'" "2008-02-19T17:21:42.892842Z":60:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::setHostAliases adding 'pbx:5060'" "2008-02-19T17:21:42.892928Z":61:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::setHostAliases adding 'pbx.smartt.com:5060'" "2008-02-19T17:21:42.892988Z":62:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipUserAgent::setHostAliases adding 'smartt.com:5060'" "2008-02-19T17:21:42.893100Z":63:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'SipUserAgent-1' this = 0xbfff0088, mTaskId = -1215206512" "2008-02-19T17:21:42.893190Z":64:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'SipRouter-9' created 0xbfff139c" "2008-02-19T17:21:42.893238Z":65:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'SipRouter-9' queue: 0xbfff14e0 queue limit: 2000" "2008-02-19T17:21:42.893481Z":66:SIP:INFO:pbx.smartt.com::00000000:SipProxy:"SipRouter: auth server route ''" "2008-02-19T17:21:42.893635Z":67:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'SipRouter-9' this = 0xbfff139c, mTaskId = -1216259184" "2008-02-19T17:21:42.893692Z":68:CDR:DEBUG:pbx.smartt.com::00000000:SipProxy:"CallStateEventWriter_DB::constructor Log type database" "2008-02-19T17:21:42.893748Z":69:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTask::_ 'ForkingProxyCseObserver-10' created 0x97561e8" "2008-02-19T17:21:42.893790Z":70:KERNEL:INFO:pbx.smartt.com::00000000:SipProxy:"OsServerTask::_ 'ForkingProxyCseObserver-10' queue: 0x975632c queue limit: 2000" "2008-02-19T17:21:42.912375Z":71:ODBC:DEBUG:pbx.smartt.com::00000000:SipProxy:"odbcConnect: Connected to database DATABASE=SIPXCDR;SERVER=localhost;UID=postgres;PWD=;DRIVER={PostgreSQL};READONLY=0;, OdbcHandle 0x9754a80" "2008-02-19T17:21:42.912425Z":72:CDR:DEBUG:pbx.smartt.com::00000000:SipProxy:"CallStateEventWriter_DB::openLog connected to database SIPXCDR" "2008-02-19T17:21:43.009120Z":73:ODBC:DEBUG:pbx.smartt.com::00000000:SipProxy:"odbcExecute: statement INSERT INTO observer_state_events VALUES (DEFAULT,'69.31.174.100:-1',0,timestamp '2008-02-19 17:21:42.893',101,'ForkingProxyCseObserver'); succeeded" "2008-02-19T17:21:43.009275Z":74:CDR:DEBUG:pbx.smartt.com::00000000:SipProxy:"CallStateEventWriter_DB::writeLog" "2008-02-19T17:21:43.009497Z":75:KERNEL:DEBUG:pbx.smartt.com::00000000:SipProxy:"OsTaskLinux::start 'ForkingProxyCseObserver-10' this = 0x97561e8, mTaskId = -1217340528" "2008-02-19T17:21:43.044265Z":76:KERNEL:WARNING:pbx.smartt.com:OsTimer-8:B7A17B90:SipProxy:"OsTimerTask::insertTimer timer to fire 34788 in the past, queue length = 0" "2008-02-19T17:21:49.859983Z":77:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: rcv addr: sock type: UDP read ready READY" "2008-02-19T17:21:49.860447Z":78:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.212---- Port: 5060----\nINVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCSeq: 1 INVITE\r\nCall-ID: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\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=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:49.861947Z":79:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::_ attempt 69.31.174.212:5060" "2008-02-19T17:21:49.862036Z":80:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::_ 9 (69.31.174.212:5060 69.31.174.100:0) socket and bind succeeded" "2008-02-19T17:21:49.862206Z":81:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::doConnect 9 (69.31.174.212:5060 69.31.174.100:33750) succeeded)" "2008-02-19T17:21:49.862274Z":82:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsTask::_ 'SipClient-11' created 0x9792fb0" "2008-02-19T17:21:49.862329Z":83:SIP:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-11]::_ created UDP unshared socket 9: host '69.31.174.212' port 5060" "2008-02-19T17:21:49.862360Z":84:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServer(SipProtocolServerBase)::createClient client: 0x9792fb0 '69.31.174.212':5060" "2008-02-19T17:21:49.862423Z":85:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0x9792fb0 isInUseForWrite 318580, client info\n remote host: 69.31.174.212:5060\n remote IP: 69.31.174.212:5060\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.862591Z":86:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.212---- Port: 5060----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:49.862781Z":87:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"INVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\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: Tue, 19 Feb 2008 17:21:49 GMT\r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:49.863214Z":88:SIP:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"ForkingProxyCseObserver message is a Call Setup" "2008-02-19T17:21:49.863443Z":89:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 7.017080 7.017080 locking\n 7.017093 0.000013 locked\n 7.017169 0.000076 reading\n 7.017520 0.000351 read\n 7.017524 0.000004 released\n 7.017543 0.000019 bytesread\n 7.018000 0.000457 dispatching\n 7.020533 0.002533 dispatched" "2008-02-19T17:21:49.864008Z":90:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"ForwardRules::parseRouteMatchContainer - routeFrom smartt.com matches smartt.com:5060" "2008-02-19T17:21:49.864205Z":91:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipRouter fowardingrules added route type 'local' to: ''" "2008-02-19T17:21:49.865806Z":92:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipSrvLookup::servers domain = '69.31.174.100', service = 'sip', socketType = UDP, port = 5070" "2008-02-19T17:21:49.866065Z":93:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipSrvLookup::servers host = '69.31.174.100', IP addr = '69.31.174.100', port = 5070, weight = 0, score = 1000.000000, priority = 0, proto = UDP" "2008-02-19T17:21:49.866915Z":94:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"OsDatagramSocket::_ attempt 69.31.174.100:5070" "2008-02-19T17:21:49.866999Z":95:KERNEL:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"OsDatagramSocket::_ 10 (69.31.174.100:5070 69.31.174.100:0) socket and bind succeeded" "2008-02-19T17:21:49.867114Z":96:KERNEL:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"OsDatagramSocket::doConnect 10 (69.31.174.100:5070 69.31.174.100:33751) succeeded)" "2008-02-19T17:21:49.867175Z":97:KERNEL:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"OsTask::_ 'SipClient-12' created 0xb7508f60" "2008-02-19T17:21:49.867226Z":98:SIP:INFO:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipClient[SipClient-12]::_ created UDP unshared socket 10: host '69.31.174.100' port 5070" "2008-02-19T17:21:49.867257Z":99:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipUDPServer(SipProtocolServerBase)::createClient client: 0xb7508f60 '69.31.174.100':5070" "2008-02-19T17:21:49.867306Z":100:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipUDPServerBase::send 0xb7508f60 isInUseForWrite 318580, client info\n remote host: 69.31.174.100:5070\n remote IP: 69.31.174.100:5070\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.867724Z":101:OUTGOING:INFO:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5070----\nINVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\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: Tue, 19 Feb 2008 17:21:49 GMT\r\nRoute: \r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:49.868306Z":102:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipTransaction::doFirstSend 0xb7505390 setting timeout 180" "2008-02-19T17:21:49.870174Z":103:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:49.870384Z":104:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33753----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:49.870962Z":105:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:49.871061Z":106:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.006641 0.006641 locking\n 0.006647 0.000006 locked\n 0.006739 0.000092 reading\n 0.006849 0.000110 read\n 0.006852 0.000003 released\n 0.006861 0.000009 bytesread\n 0.007018 0.000157 dispatching\n 0.007522 0.000504 dispatched" "2008-02-19T17:21:49.874813Z":107:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:49.875107Z":108:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33753----\nSIP/2.0 302 Moved Temporarily\r\nContact: ;q=0.9\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 19 Feb 2008 17:21:49 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" "2008-02-19T17:21:49.875516Z":109:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipMessage::setAckData inviteRequest->getRequestUri() = 'sip:6048313423@smartt.com;user=phone'" "2008-02-19T17:21:49.875996Z":110:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0xb7508f60 isInUseForWrite 318580, client info\n remote host: 69.31.174.100:5070\n remote IP: 69.31.174.100:5070\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.876225Z":111:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5070----\nACK sip:6048313423@smartt.com;user=phone SIP/2.0\r\nRoute: \r\nContact: sip:200@69.31.174.212\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 ACK\r\nMax-Forwards: 20\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:49.876426Z":112:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::handleChildIncoming 0x9790328" "2008-02-19T17:21:49.878073Z":113:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipSrvLookup::servers domain = '69.31.174.100', service = 'sip', socketType = UDP, port = 5080" "2008-02-19T17:21:49.878142Z":114:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipSrvLookup::servers host = '69.31.174.100', IP addr = '69.31.174.100', port = 5080, weight = 0, score = 1000.000000, priority = 0, proto = UDP" "2008-02-19T17:21:49.878938Z":115:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::_ attempt 69.31.174.100:5080" "2008-02-19T17:21:49.879015Z":116:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::_ 11 (69.31.174.100:5080 69.31.174.100:0) socket and bind succeeded" "2008-02-19T17:21:49.879057Z":117:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsDatagramSocket::doConnect 11 (69.31.174.100:5080 69.31.174.100:33754) succeeded)" "2008-02-19T17:21:49.879110Z":118:KERNEL:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"OsTask::_ 'SipClient-13' created 0xb75153d8" "2008-02-19T17:21:49.879159Z":119:SIP:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-13]::_ created UDP unshared socket 11: host '69.31.174.100' port 5080" "2008-02-19T17:21:49.879188Z":120:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServer(SipProtocolServerBase)::createClient client: 0xb75153d8 '69.31.174.100':5080" "2008-02-19T17:21:49.879242Z":121:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0xb75153d8 isInUseForWrite 318580, client info\n remote host: 69.31.174.100:5080\n remote IP: 69.31.174.100:5080\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.879658Z":122:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5080----\nINVITE sip:6048313423@burn-gw01.smartt.com;user=phone;transport=udp SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 1 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\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: Tue, 19 Feb 2008 17:21:49 GMT\r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:49.880096Z":123:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::doFirstSend 0xb7511b40 setting timeout 180" "2008-02-19T17:21:49.880562Z":124:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 302 Moved Temporarily\r\nContact: ;q=0.9\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-5c45418678e1af01739b1019913013a8\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 19 Feb 2008 17:21:49 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" "2008-02-19T17:21:49.880720Z":125:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.003669 0.003669 locking\n 0.003676 0.000007 locked\n 0.003763 0.000087 reading\n 0.003943 0.000180 read\n 0.003946 0.000003 released\n 0.003961 0.000015 bytesread\n 0.004124 0.000163 dispatching\n 0.009558 0.005434 dispatched" "2008-02-19T17:21:49.882231Z":126:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:49.882448Z":127:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33755----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:49.883147Z":128:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:49.883267Z":129:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.001422 0.001422 locking\n 0.001428 0.000006 locked\n 0.001517 0.000089 reading\n 0.001634 0.000117 read\n 0.001636 0.000002 released\n 0.001645 0.000009 bytesread\n 0.001814 0.000169 dispatching\n 0.002438 0.000624 dispatched" "2008-02-19T17:21:49.885240Z":130:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:49.885503Z":131:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33755----\nSIP/2.0 407 Proxy Authentication Required\r\nServer: sipX/3.8.1 sipX/authproxy (Linux)\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nProxy-Authenticate: Digest realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\"\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:49.885805Z":132:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipMessage::setAckData inviteRequest->getRequestUri() = 'sip:6048313423@burn-gw01.smartt.com;user=phone;transport=udp'" "2008-02-19T17:21:49.886177Z":133:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0xb75153d8 isInUseForWrite 318580, client info\n remote host: 69.31.174.100:5080\n remote IP: 69.31.174.100:5080\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.886372Z":134:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5080----\nACK sip:6048313423@burn-gw01.smartt.com;user=phone;transport=udp SIP/2.0\r\nContact: sip:200@69.31.174.212\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 ACK\r\nMax-Forwards: 20\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:49.886516Z":135:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::handleChildIncoming 0x9790328" "2008-02-19T17:21:49.886801Z":136:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::handleChildIncoming: response 407 for \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r" "2008-02-19T17:21:49.887125Z":137:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0x9792fb0 isInUseForWrite 318580, client info\n remote host: 69.31.174.212:5060\n remote IP: 69.31.174.212:5060\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:49.887372Z":138:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.212---- Port: 5060----\nSIP/2.0 407 Proxy Authentication Required\r\nServer: sipX/3.8.1 sipX/authproxy (Linux)\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nProxy-Authenticate: Digest realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\"\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:49.887957Z":139:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 407 Proxy Authentication Required\r\nServer: sipX/3.8.1 sipX/authproxy (Linux)\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-a279944372c2f2b673c125560b435dfd\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nProxy-Authenticate: Digest realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\"\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:49.888094Z":140:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP User agent delayed dispatch message:\nSIP/2.0 407 Proxy Authentication Required\r\nServer: sipX/3.8.1 sipX/authproxy (Linux)\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 1 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nProxy-Authenticate: Digest realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\"\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\nContent-Length: 0\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:49.888311Z":141:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.001891 0.001891 locking\n 0.001897 0.000006 locked\n 0.001993 0.000096 reading\n 0.002141 0.000148 read\n 0.002144 0.000003 released\n 0.002155 0.000011 bytesread\n 0.002272 0.000117 dispatching\n 0.004972 0.002700 dispatched" "2008-02-19T17:21:49.905303Z":142:ODBC:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"odbcExecute: statement INSERT INTO call_state_events VALUES (DEFAULT,'69.31.174.100:-1',1,timestamp '2008-02-19 17:21:49.863','R',1,'cd31c917-fb3cb1a1-3b7224fe@69.31.174.212','A090EA9B-19995270','','\"Chris St Denis\" ;tag=A090EA9B-19995270','','','','',0,'',''); succeeded" "2008-02-19T17:21:49.905349Z":143:CDR:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"CallStateEventWriter_DB::writeLog" "2008-02-19T17:21:49.905495Z":144:SIP:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"ForkingProxyCseObserver message is a Call Failure" "2008-02-19T17:21:49.916128Z":145:ODBC:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"odbcExecute: statement INSERT INTO call_state_events VALUES (DEFAULT,'69.31.174.100:-1',2,timestamp '2008-02-19 17:21:49.905','F',1,'cd31c917-fb3cb1a1-3b7224fe@69.31.174.212','A090EA9B-19995270','','\"Chris St Denis\" ;tag=A090EA9B-19995270','','','','',407,'Proxy Authentication Required',''); succeeded" "2008-02-19T17:21:49.916170Z":146:CDR:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"CallStateEventWriter_DB::writeLog" "2008-02-19T17:21:49.963146Z":147:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:49.963343Z":148:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.212---- Port: 5060----\nACK sip:6048313423@smartt.com SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCSeq: 1 ACK\r\nCall-ID: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nMax-Forwards: 70\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:49.963950Z":149:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"ACK sip:6048313423@smartt.com SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKcfcece7d732FBF3C\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 1 ACK\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nMax-Forwards: 70\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:21:49 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:49.964044Z":150:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.074766 0.074766 locking\n 0.074772 0.000006 locked\n 0.074831 0.000059 reading\n 0.074962 0.000131 read\n 0.074964 0.000002 released\n 0.074973 0.000009 bytesread\n 0.075262 0.000289 dispatching\n 0.075658 0.000396 dispatched" "2008-02-19T17:21:50.031093Z":151:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:50.031459Z":152:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.212---- Port: 5060----\nINVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCSeq: 2 INVITE\r\nCall-ID: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 70\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:50.032607Z":153:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0x9792fb0 isInUseForWrite 318581, client info\n remote host: 69.31.174.212:5060\n remote IP: 69.31.174.212:5060\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:50.032770Z":154:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.212---- Port: 5060----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:50.032904Z":155:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"INVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 2 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 70\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Tue, 19 Feb 2008 17:21:50 GMT\r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:50.033273Z":156:SIP:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"ForkingProxyCseObserver message is a Call Setup" "2008-02-19T17:21:50.033664Z":157:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.066982 0.066982 locking\n 0.066987 0.000005 locked\n 0.067046 0.000059 reading\n 0.067309 0.000263 read\n 0.067311 0.000002 released\n 0.067322 0.000011 bytesread\n 0.067665 0.000343 dispatching\n 0.069548 0.001883 dispatched" "2008-02-19T17:21:50.034010Z":158:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"ForwardRules::parseRouteMatchContainer - routeFrom smartt.com matches smartt.com:5060" "2008-02-19T17:21:50.034202Z":159:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipRouter fowardingrules added route type 'local' to: ''" "2008-02-19T17:21:50.035633Z":160:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipSrvLookup::servers domain = '69.31.174.100', service = 'sip', socketType = UDP, port = 5070" "2008-02-19T17:21:50.035735Z":161:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipSrvLookup::servers host = '69.31.174.100', IP addr = '69.31.174.100', port = 5070, weight = 0, score = 1000.000000, priority = 0, proto = UDP" "2008-02-19T17:21:50.036514Z":162:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipUDPServerBase::send 0xb7508f60 isInUseForWrite 318581, client info\n remote host: 69.31.174.100:5070\n remote IP: 69.31.174.100:5070\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:50.037012Z":163:OUTGOING:INFO:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5070----\nINVITE sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 2 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 20\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Tue, 19 Feb 2008 17:21:50 GMT\r\nRoute: \r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:50.037467Z":164:SIP:DEBUG:pbx.smartt.com:SipRouter-9:B7815B90:SipProxy:"SipTransaction::doFirstSend 0xb7522478 setting timeout 180" "2008-02-19T17:21:50.038597Z":165:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:50.038779Z":166:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33753----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:50.039339Z":167:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:21:50 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:50.039448Z":168:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.004856 0.004856 locking\n 0.004860 0.000004 locked\n 0.004931 0.000071 reading\n 0.005036 0.000105 read\n 0.005038 0.000002 released\n 0.005048 0.000010 bytesread\n 0.005206 0.000158 dispatching\n 0.005697 0.000491 dispatched" "2008-02-19T17:21:50.041791Z":169:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:50.042059Z":170:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33753----\nSIP/2.0 302 Moved Temporarily\r\nContact: ;q=0.9\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 19 Feb 2008 17:21:50 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" "2008-02-19T17:21:50.042347Z":171:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipMessage::setAckData inviteRequest->getRequestUri() = 'sip:6048313423@smartt.com;user=phone'" "2008-02-19T17:21:50.042758Z":172:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0xb7508f60 isInUseForWrite 318581, client info\n remote host: 69.31.174.100:5070\n remote IP: 69.31.174.100:5070\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:50.042971Z":173:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5070----\nACK sip:6048313423@smartt.com;user=phone SIP/2.0\r\nRoute: \r\nContact: sip:200@69.31.174.212\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 ACK\r\nMax-Forwards: 20\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:21:50.043153Z":174:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::handleChildIncoming 0xb751aca8" "2008-02-19T17:21:50.044580Z":175:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipSrvLookup::servers domain = '69.31.174.100', service = 'sip', socketType = UDP, port = 5080" "2008-02-19T17:21:50.044650Z":176:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipSrvLookup::servers host = '69.31.174.100', IP addr = '69.31.174.100', port = 5080, weight = 0, score = 1000.000000, priority = 0, proto = UDP" "2008-02-19T17:21:50.045497Z":177:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0xb75153d8 isInUseForWrite 318581, client info\n remote host: 69.31.174.100:5080\n remote IP: 69.31.174.100:5080\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:21:50.045953Z":178:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.100---- Port: 5080----\nINVITE sip:6048313423@burn-gw01.smartt.com;user=phone;transport=udp SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-323d60fa0a267f9f69fdabc1fd531a29\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 2 INVITE\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.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.2.2.0084\r\nSupported: 100rel,replaces\r\nAllow-Events: talk,hold,conference\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 19\r\nContent-Type: application/sdp\r\nContent-Length: 251\r\nDate: Tue, 19 Feb 2008 17:21:50 GMT\r\n\r\nv=0\r\no=- 1203441464 1203441464 IN IP4 69.31.174.212\r\ns=Polycom IP Phone\r\nc=IN IP4 69.31.174.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" "2008-02-19T17:21:50.046451Z":179:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipTransaction::doFirstSend 0xb752de68 setting timeout 180" "2008-02-19T17:21:50.046966Z":180:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 302 Moved Temporarily\r\nContact: ;q=0.9\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-b01c2fb40aa1dd011ce7008c8ff1fb7f\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 19 Feb 2008 17:21:50 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" "2008-02-19T17:21:50.047085Z":181:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.002267 0.002267 locking\n 0.002274 0.000007 locked\n 0.002348 0.000074 reading\n 0.002514 0.000166 read\n 0.002517 0.000003 released\n 0.002531 0.000014 bytesread\n 0.002679 0.000148 dispatching\n 0.007545 0.004866 dispatched" "2008-02-19T17:21:50.047788Z":182:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:50.047976Z":183:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33755----\nSIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-323d60fa0a267f9f69fdabc1fd531a29\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:21:50.048589Z":184:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SIP/2.0 100 Trying\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-323d60fa0a267f9f69fdabc1fd531a29\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:21:50 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:21:50.048696Z":185:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 0.000636 0.000636 locking\n 0.000640 0.000004 locked\n 0.000705 0.000065 reading\n 0.000811 0.000106 read\n 0.000813 0.000002 released\n 0.000822 0.000009 bytesread\n 0.000978 0.000156 dispatching\n 0.001521 0.000543 dispatched" "2008-02-19T17:21:50.067563Z":186:ODBC:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"odbcExecute: statement INSERT INTO call_state_events VALUES (DEFAULT,'69.31.174.100:-1',3,timestamp '2008-02-19 17:21:50.033','R',2,'cd31c917-fb3cb1a1-3b7224fe@69.31.174.212','A090EA9B-19995270','','\"Chris St Denis\" ;tag=A090EA9B-19995270','','','','',0,'',''); succeeded" "2008-02-19T17:21:50.067603Z":187:CDR:DEBUG:pbx.smartt.com:ForkingProxyCseObserver-10:B770DB90:SipProxy:"CallStateEventWriter_DB::writeLog" "2008-02-19T17:21:50.369750Z":188:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2008-02-19T17:21:50.369804Z":189:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x9790328" "2008-02-19T17:21:50.381672Z":190:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2008-02-19T17:21:50.381711Z":191:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"sipTransaction::handleChildTimeoutEvent 0x9790328" "2008-02-19T17:21:50.538662Z":192:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2008-02-19T17:21:50.538704Z":193:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"sipTransaction::handleChildTimeoutEvent 0xb751aca8" "2008-02-19T17:21:50.547637Z":194:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipTransaction::handleResendEvent no response, TRANSACTION_COMPLETE" "2008-02-19T17:21:50.547677Z":195:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"sipTransaction::handleChildTimeoutEvent 0xb751aca8" "2008-02-19T17:21:51.254461Z":196:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run 0x9753328 socket 0x97520a8 host: sock addr: 0.0.0.0 via addr: 69.31.174.212 rcv addr: 69.31.174.212 sock type: UDP read ready READY" "2008-02-19T17:21:51.254964Z":197:INCOMING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"Read SIP message:\n----Remote Host:69.31.174.100---- Port: 33755----\nSIP/2.0 180 Ringing\r\nVia: SIP/2.0/UDP 69.31.174.100;branch=z9hG4bK-323d60fa0a267f9f69fdabc1fd531a29\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\";tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: ;tag=1c540565317\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 INVITE\r\nContact: \r\nRecord-Route: ;tag=A090EA9B-19995270\r\nTo: \r\nCSeq: 2 CANCEL\r\nCall-ID: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nContact: \r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.2.2.0084\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 70\r\nContent-Length: 0\r\n\r\n====================END====================\n" "2008-02-19T17:22:49.905104Z":283:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUDPServerBase::send 0x9792fb0 isInUseForWrite 318640, client info\n remote host: 69.31.174.212:5060\n remote IP: 69.31.174.212:5060\n remote Via address: :-1\n received address: :-1" "2008-02-19T17:22:49.905277Z":284:OUTGOING:INFO:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:69.31.174.212---- Port: 5060----\nSIP/2.0 200 OK\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nCseq: 2 CANCEL\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n" "2008-02-19T17:22:49.905482Z":285:INCOMING_PARSED:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"CANCEL sip:6048313423@smartt.com;user=phone SIP/2.0\r\nVia: SIP/2.0/UDP 69.31.174.212;branch=z9hG4bKe8c1f285FBA38264\r\nFrom: \"Chris St Denis\" ;tag=A090EA9B-19995270\r\nTo: \r\nCseq: 2 CANCEL\r\nCall-Id: cd31c917-fb3cb1a1-3b7224fe@69.31.174.212\r\nContact: \r\nUser-Agent: PolycomSoundPointIP-SPIP_301-UA/2.2.2.0084\r\nProxy-Authorization: Digest username=\"200\", realm=\"smartt.com\", nonce=\"59fb45e7803ef2dff612d701a76a986c1203441709\", uri=\"sip:6048313423@smartt.com;user=phone\", response=\"9619faebd1ce024c09f9cfd9c81daaf1\", algorithm=MD5\r\nMax-Forwards: 70\r\nContent-Length: 0\r\nDate: Tue, 19 Feb 2008 17:22:49 GMT\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n" "2008-02-19T17:22:49.905603Z":286:SIP:DEBUG:pbx.smartt.com:SipClient-7:B7B18B90:SipProxy:"SipClient[SipClient-7]::run time log: \n Time Increment Name\n 0.000000 waiting\n 35.032063 35.032063 locking\n 35.032069 0.000006 locked\n 35.032147 0.000078 reading\n 35.032320 0.000173 read\n 35.032322 0.000002 released\n 35.032332 0.000010 bytesread\n 35.032616 0.000284 dispatching\n 35.033696 0.001080 dispatched" "2008-02-19T17:23:02.841869Z":287:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipUDPServer::Removing old client 0xb7508f60:\n remote host: 69.31.174.100:5070\n remote IP: 69.31.174.100:5070\n remote Via address: :-1\n received address: :-1\r" "2008-02-19T17:23:02.841932Z":288:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipUDPServer::Removing old client 0xb75153d8:\n remote host: 69.31.174.100:5080\n remote IP: 69.31.174.100:5080\n remote Via address: :-1\n received address: :-1\r" "2008-02-19T17:23:02.841978Z":289:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipUDPServer::removeOldClients deleting 2 of 3 SipClients (0 busy)" "2008-02-19T17:23:02.842012Z":290:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipClient[SipClient-12]::~SipClient 0xb7508f60 socket 0xb7508a18 closing UDP socket" "2008-02-19T17:23:02.842162Z":291:KERNEL:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"OsSocket::~ close socket 10" "2008-02-19T17:23:02.842212Z":292:KERNEL:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"OsTaskLinux::waitUntilShutDown 'SipUserAgent-1' for 'SipClient-12' UNINITIALIZED" "2008-02-19T17:23:02.842269Z":293:SIP:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"SipClient[SipClient-13]::~SipClient 0xb75153d8 socket 0xb7514e90 closing UDP socket" "2008-02-19T17:23:02.842355Z":294:KERNEL:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"OsSocket::~ close socket 11" "2008-02-19T17:23:02.842394Z":295:KERNEL:DEBUG:pbx.smartt.com:SipUserAgent-1:B7916B90:SipProxy:"OsTaskLinux::waitUntilShutDown 'SipUserAgent-1' for 'SipClient-13' UNINITIALIZED"