1026140112|sip |3|00|CTcpSocket::OnRecvData
1026140112|sip |3|00|OnEvRequest unsuported method
1026140112|sip |2|00|new UA Server Non-INVITE trans state 'callingTrying', timeout=0 (0x408ffb08)
1026140112|sip |4|00|SSL_get_error Error code=5,rc(0)
1026140112|sip |4|00|TLS Listen Thread Exit
1026140112|sip |1|00|MsgSipTcpSocketStatus socket 164 status 0 m_bIsReconnectNeeded 0
1026140112|sip |2|00|CTcpSocket::OnSocketStatus socket 164(0x408febc8) bStatus 0
1026140112|sip |3|00|CTcpSocket::~CTcpSocket socket 164 entry for 0x408febc8
1026140112|sip |3|00|CTcpSocket::~CTcpSocket socket 164(0x408febc8) SSL_shutdown rc=0, ltf(1)
1026140112|sip |3|00|CTcpSocket::~CTcpSocket socket 164(0x408febc8) close
1026140112|sip |3|00|CTcpSocket::~CTcpSocket Got lock
1026140112|sip |1|00|Error server finished
1026140141|sip |1|00|CTrans::TimeOut500ms m_nMainTimeoutCount == 0. Call SndMsgFail
1026140141|sip |3|00|CTrans::TimeOut500ms Self Generated 480 Response
1026140141|sip |3|00|UA Client Non-INVITE REGISTER trans state 'callingTrying'->'completed' by 480 resp 10 timeout(0x408fa528)
1026140141|sip |2|00|CTrans:: REGISTER NonInv reTrans ALREADY stopped in 'completed' state at retryCount 0 code 480, timeout=10 (0x408fa528)
1026140141|sip |1|00|CTrans:: ResponseProcess | Follow SIP logic
1026140141|sip |3|00|CTrans::ResponseProcess Self Generated 480. RROFO Invalidate Registration
1026140141|sip |2|00|CTrans::AbandonSockets server 1 entry 0x408fa528
1026140141|sip |2|00|CTrans::AbandonSocket '10.32.16.43:443;transport=tls
1026140141|sip |3|00|CTcp::AbandonSockets socket for '10.32.16.43':443
1026140141|sip |1|00|Dialog 'id01e17bb8' State 'Trying'->'Confirmed'
1026140141|sip |3|00|CUser::OnRegistered Entry for call 0xd69528 with expires 0 ticks Transport 'TLS' inval Method 2 RROFO 0
1026140141|sip |3|00|CUser::NewWorkingServer user 0 oldServer 0 newServer 0 expires 0
1026140141|sip |3|00|SipOnEvNewWorkingServer User 0, old 0, new 0, expire 0
1026140141|so |3|00|[SoNcasC]: Handling SoNcasFailOverPending now
1026140141|sip |3|00|SipOnEvRegistrarUpdate User 0, index 0, state 0, expire 0, working 1 bIsOcs 0
1026140141|sip |3|00|CUser::HasNoCallCallInState for User dsmith,Index 0, total calls 1 state 'SubscribeAddressBook' rc 0 of type Not Applicable
1026140141|sip |3|00|CUser::HasNoCallCallInState for User dsmith,Index 0, total calls 1 state 'ServiceSoap' rc 0 of type Not Applicable
1026140141|sip |1|00|Dialog 'id01e17bb8' State 'Confirmed'->'Terminated'
1026140141|sip |2|00|CStkDialog::CStkDialog SetAddressLocal Config 'dsmith' <:0>
1026140141|sip |2|00|CStkDialog::CStkDialog AddressLocal set to Config
1026140141|sip |3|00|CStkDialog::SetAddressLocal localTag set to ''
1026140141|sip |3|00|CStkDialog::SetAddressLocal new address added of 1
1026140141|sip |2|00|CStkDialog::CStkDialog TAG '98F78797-77AFBCB6' generated
1026140141|sip |2|00|CStkDialog::CStkDialog local addr 'dsmith' <:0> Tag '98F78797-77AFBCB6'
1026140141|sip |2|00|CStkDialog::CStkDialog exit 0xd813e4 local list size 1
1026140141|sip |2|00|CStkDialogList::CreateDialogObject localTarg usr 'dsmith'
1026140141|sip |3|00|Reg UAC Response: code 480 consecutive Attempts 2 baseTimeOut 10 maxTimeOut 180 exponTimeOut 40 randomTimeOut 35
1026140141|sip |3|00|Reg UAC Response: code 480 new m_nExpire 70 m_nOverlap 0 Trans 0x408fa528
1026140141|sip |3|00|CTrans::TimeOut500ms Self Generated 480 Response, m_nMainTimeoutCount 10 m_nExipres 70 method 'REGISTER'
1026140141|sip |1|00|Client State finished REGISTER (0xd81098)
1026140141|sip |3|00|SipStartFailOver 0
1026140215|sip |3|00|NoCall::TimeOut500ms 'Registering' m_nExpire == 0 RegisterCall -> Schedule Register listSize 0 lTimeout 0
1026140215|sip |1|00|Schedule 'Register' nEvent 0 busyFlag 0 num events waiting 0
1026140215|sip |2|00|new UA Client Non-INVITE trans state 'callingTrying', timeout=0 (0x408fa528)
1026140215|sip |3|00|RegClient:RegClient expire 66 overlap 0
1026140215|sip |2|00|SendCommand: reqDest 'mycompany.org' isLync 0 isGRUU 0 isIP 0 useEffectiveProxy 1
1026140215|sip |1|00|SendCommand: isLync 0 isGRUU 0 isIP 0 useEffectiveProxy 1
1026140215|sip |1|00|CreateFailOverProxyList : Reg to Domain 'mycompany.org' nPort 0, lkup 6
1026140215|sip |1|00|CreateFailOverProxyList : Domain is in user part
1026140215|sip |1|00|CreateFailOverProxyList : For REGISTER Request nPort 0
1026140215|cfg |4|00|Prov|[CfgLyncSipSrvDiscover::cbFoundOption] Invalid STS-URI: '://:'
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' 0 DHCP servers received
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sipinternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipinternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sip.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sip.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |1|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 443 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tls): result 0 '10.32.16.43' port 443 isInBound 0
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sip.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sip.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sip.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |4|00|doDnsListLookup(tls): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |4|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList 'sipexternal.mycompany.org' found no records
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5061 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5061 isInBound 0
1026140219|sip |1|00|doDnsListLookup(tcp): doDnsSrvLookupForARecordList for 'autodiscover.mycompany.org' port 5060 returned 1 results
1026140219|sip |1|00|doDnsListLookup(tcp): result 0 '10.32.16.43' port 5060 isInBound 0
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 0 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tls port 443
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 1 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 2 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 3 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 4 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 5 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 6 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5061
1026140219|sip |3|00|CreateFailOverProxyList : 'Auto Discovery' for 'mycompany.org' port 0 index 7 has domain 'autodiscover.mycompany.org' IP '10.32.16.43' on tcp port 5060
1026140219|sip |2|00|CreateFailOverProxyList : Exit 'Auto Discovery' lookup with 8 IP Addresses
1026140219|sip |2|00|CreateFailOverProxyList : IP 1 is '10.32.16.43' on tls port 443
1026140219|sip |2|00|CreateFailOverProxyList : IP 2 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 3 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 4 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 5 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 6 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 7 is '10.32.16.43' on tcp port 5061
1026140219|sip |2|00|CreateFailOverProxyList : IP 8 is '10.32.16.43' on tcp port 5060
1026140219|sip |1|00|CTrans:: SendCommand | this=408fa528, bVQMonMessage=0, m_pCall->m_pUser->m_bOBFailOverReRegOn=0, m_pCall->m_pUser->m_bVQMonFailoverEnabled=1
1026140219|sip |1|00|CTcp::Send(TLS) entry for address 10.32.16.43 port 443 can Connect 1 canFailOver 0
1026140219|sip |1|00|CTcp::Send(TLS) address 10.32.16.43 port 443 can Connect 1
1026140219|sip |2|00|CTcpSocket::CTcpSocket entry m_bIsTcpPersistentConnection 0, Inbound 0 Timeout 120 this 0x408fdb48
1026140219|sip |3|00|Open socket 164(0x408fdb48)
1026140219|sip |1|00|SetRemoteAddress set SND BUF to 64000 OK
1026140219|sip |1|00|SetRemoteAddress set SEND TIMEOUT to 100ms OK
1026140219|sip |3|00|SetRemoteAddress OK on try 1 nPort 59095 0x408fdb48
1026140219|sip |3|00|CTcpSocket::ConnectThreadLocal. Trying to connect IP 10.32.16.43:443
1026140219|sip |1|00|Task name tTCPCnt164
1026140219|sip |3|00|connectNonBlock is successful
1026140219|sip |1|00|CTcp::Send(TLS) exit for address 10.32.16.43 port 443 can Connect 1 status 1 canFailOver 0 FoundSocket 0
1026140219|sip |1|00|MsgSipTcpSocketStatus socket 164 status 1 m_bIsReconnectNeeded 0
1026140219|sip |2|00|CTcpSocket::OnSocketStatus socket 164(0x408fdb48) bStatus 1
1026140219|sip |2|00|MakeTlsConnection: SSL_connect OK : TLS Handshake completed successfully
1026140219|sip |1|00|[TLS] hostname we are connected to is: 'autodiscover.mycompany.org' IP '10.32.16.43' port 443
1026140219|sip |3|00|[TLS] Validating Subject Alternative Name(s) (SAN) and Common Name (CN) against the following:
1026140219|sip |3|00|[TLS] Hostname: mycompany.org
1026140219|sip |3|00|[TLS] Outbound Proxy: mycompany.org
1026140219|sip |3|00|[TLS] Hostname connection: autodiscover.mycompany.org
1026140219|sip |1|00|[TLS] Comparing certificate SAN type DNS: 'mail.mycompany.org'
1026140219|sip |1|00|[TLS] Comparing certificate SAN type DNS: 'mycompany.org'
1026140219|sip |3|00|[TLS] Validating certificate SAN of type DNS with 'mycompany.org'
1026140219|sip |3|00|[TLS] Server Certificate SAN or CN validation success. SSL verify result 0
1026140219|sip |1|00|MakeTlsConnection: post_connection_checks passed
1026140219|sip |3|00|MakeTlsConnection: connection succeeded
1026140219|sip |0|00|> Data Send TLS 10.32.16.43:443
1026140219|sip |0|00| REGISTER sip:mycompany.org;transport=tls SIP/2.0
1026140219|sip |0|00| Via: SIP/2.0/TLS 10.32.12.32:59095;branch=z9hG4bKdc65216196B45F08
1026140219|sip |0|00| From: "dsmith" <sip:>;tag=98F78797-77AFBCB6;epid=64167f8321ed
1026140219|sip |0|00| To: <sip:>
1026140219|sip |0|00| CSeq: 1 REGISTER
1026140219|sip |0|00| Call-ID:
1026140219|sip |0|00| Contact: <sip::59095;transport=tls>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:88373d67-1c3d-552d-8aff-814e957bc12b>"
1026140219|sip |0|00| User-Agent: Polycom/5.4.0.10182 PolycomVVX-VVX_201-UA/5.4.0.10182
1026140219|sip |0|00| Accept-Language: en
1026140219|sip |0|00| Ms-device-info: MAC=64-16-7F-83-21-ED, vendor=POLYCOM, version=PolycomVVX-VVX_201-5.4.0.10182
1026140219|sip |0|00| ms-keep-alive: UAC;hop-hop=yes
1026140219|sip |0|00| Supported: msrtc-event-categories,adhoclist,ms-cluster-failover,ms-userservices-state-notification,gruu-10,gruu
1026140219|sip |0|00| Event: registration
1026140219|sip |0|00| ms-subnet: 10.32.12.0
1026140219|sip |0|00| Max-Forwards: 70
1026140219|sip |0|00| Content-Length: 0
1026140219|sip |0|00|
1026140219|sip |1|00|CTcpSocket::SendData TLS queuedTxData = 0 TotalLen 974 loop count 1 maxQueueDepth 40000
1026140219|sip |1|00|CTcpSocket::SendData TLS Sent 974 loop count 1
1026140219|sip |0|00|< Data received TLS
1026140219|sip |0|00| HTTP/1.1 400 Bad Request
1026140219|sip |0|00| Content-Type: text/html; charset=us-ascii
1026140219|sip |0|00| Server: Microsoft-HTTPAPI/2.0
1026140219|sip |0|00| Date: Wed, 26 Oct 2016 18:02:19 GMT
1026140219|sip |0|00| Connection: close
1026140219|sip |0|00| Content-Length: 311
1026140219|sip |0|00|
1026140219|sip |0|00| <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01//EN""
1026140219|sip |0|00| <HTML<HEAD<TITLE>Bad Request</TITLE>
1026140219|sip |0|00| <META HTTP-EQUIV="Content-Type" Content="text/html; charset=us-ascii"</HEAD>
1026140219|sip |0|00| <BODY<h2>Bad Request</h2>
1026140219|sip |0|00| <hr<p>HTTP Error 400. The request is badly formed.</p>
1026140219|sip |0|00| </BODY</HTML>
1026140219|sip |1|00|MsgSipTcpPacket
1026140219|sip |3|00|CTcpSocket::OnRecvData
1026140219|sip |3|00|OnEvRequest unsuported method
1026140219|sip |2|00|new UA Server Non-INVITE trans state 'callingTrying', timeout=0 (0x408ff548)
1026140219|sip |4|00|SSL_get_error Error code=5,rc(0)
1026140219|sip |4|00|TLS Listen Thread Exit
1026140219|sip |1|00|MsgSipTcpSocketStatus socket 164 status 0 m_bIsReconnectNeeded 0
1026140219|sip |2|00|CTcpSocket::OnSocketStatus socket 164(0x408fdb48) bStatus 0
1026140219|sip |3|00|CTcpSocket::~CTcpSocket socket 164 entry for 0x408fdb48
1026140219|sip |3|00|CTcpSocket::~CTcpSocket socket 164(0x408fdb48) SSL_shutdown rc=0, ltf(1)
1026140219|sip |3|00|CTcpSocket::~CTcpSocket socket 164(0x408fdb48) close
1026140219|sip |3|00|CTcpSocket::~CTcpSocket Got lock
1026140219|log |5|00|Skipped uploading of failed log file because no of failures are > 4 or last upload failed time is < 60 seconds.
1026140219|sip |1|00|Error server finished
1026140248|sip |1|00|CTrans::TimeOut500ms m_nMainTimeoutCount == 0. Call SndMsgFail
1026140248|sip |3|00|CTrans::TimeOut500ms Self Generated 480 Response
1026140248|sip |3|00|UA Client Non-INVITE REGISTER trans state 'callingTrying'->'completed' by 480 resp 10 timeout(0x408fa528)
1026140248|sip |2|00|CTrans:: REGISTER NonInv reTrans ALREADY stopped in 'completed' state at retryCount 0 code 480, timeout=10 (0x408fa528)
1026140248|sip |1|00|CTrans:: ResponseProcess | Follow SIP logic
1026140248|sip |3|00|CTrans::ResponseProcess Self Generated 480. RROFO Invalidate Registration
1026140248|sip |2|00|CTrans::AbandonSockets server 1 entry 0x408fa528
1026140248|sip |2|00|CTrans::AbandonSocket '10.32.16.43:443;transport=tls
1026140248|sip |3|00|CTcp::AbandonSockets socket for '10.32.16.43':443
1026140248|sip |1|00|Dialog 'id0209e2d4' State 'Trying'->'Confirmed'
1026140248|sip |3|00|CUser::OnRegistered Entry for call 0xd69528 with expires 0 ticks Transport 'TLS' inval Method 2 RROFO 0
1026140248|sip |3|00|CUser::NewWorkingServer user 0 oldServer 0 newServer 0 expires 0
1026140248|sip |3|00|SipOnEvNewWorkingServer User 0, old 0, new 0, expire 0
1026140248|so |3|00|[SoNcasC]: Handling SoNcasFailOverPending now
1026140248|sip |3|00|SipOnEvRegistrarUpdate User 0, index 0, state 0, expire 0, working 1 bIsOcs 0
1026140248|sip |3|00|CUser::HasNoCallCallInState for User dsmith,Index 0, total calls 1 state 'SubscribeAddressBook' rc 0 of type Not Applicable
1026140248|sip |3|00|CUser::HasNoCallCallInState for User dsmith,Index 0, total calls 1 state 'ServiceSoap' rc 0 of type Not Applicable
1026140248|sip |1|00|Dialog 'id0209e2d4' State 'Confirmed'->'Terminated'
1026140248|sip |2|00|CStkDialog::CStkDialog SetAddressLocal Config 'dsmith' <:0>
1026140248|sip |2|00|CStkDialog::CStkDialog AddressLocal set to Config
1026140248|sip |3|00|CStkDialog::SetAddressLocal localTag set to ''
1026140248|sip |3|00|CStkDialog::SetAddressLocal new address added of 1
1026140248|sip |2|00|CStkDialog::CStkDialog TAG 'B19CA29E-DE807869' generated
1026140248|sip |2|00|CStkDialog::CStkDialog local addr 'dsmith' <:0> Tag 'B19CA29E-DE807869'
1026140248|sip |2|00|CStkDialog::CStkDialog exit 0xd81098 local list size 1
1026140248|sip |2|00|CStkDialogList::CreateDialogObject localTarg usr 'dsmith'
1026140248|sip |3|00|Reg UAC Response: code 480 consecutive Attempts 3 baseTimeOut 10 maxTimeOut 180 exponTimeOut 80 randomTimeOut 51
1026140248|sip |3|00|Reg UAC Response: code 480 new m_nExpire 102 m_nOverlap 0 Trans 0x408fa528
1026140248|sip |3|00|CTrans::TimeOut500ms Self Generated 480 Response, m_nMainTimeoutCount 10 m_nExipres 102 method 'REGISTER'
1026140248|sip |1|00|Client State finished REGISTER (0xd813e4)
1026140248|sip |3|00|SipStartFailOver 0
|1|00|Client State finished SUBSCRIBE (0xd81dc8)