redline Posted January 23, 2017 #1 Share Posted January 23, 2017 Hallo Zusammen, wir haben ein Problem bei der Registrierung eines auf All-IP migrierten Anschlusses. Es handelt sich um einen DeutschlandLAN IP Voice/DATA Anschluss an einer SwyxWare 2015 R3.2. Via NAT sind die Ports 65002 (UDP) und 55000-57000 (TCP/UDP) an den SwyxServer weitergeleitet. Die Firewall auf dem Server ist derzeit komplett inaktiv. Der SIP-Trunk ist wie folgt konfiguriert: Nun ist es so, dass der Trunk für etwa 30 Sekunden angemeldet ist, dann aber wieder abgemeldet und wieder von vorne anfängt. Das LineManager-Log liefert folgendes (anonymisiert): Quote ~ ~REGISTER sip:192.168.0.25;transport=tcp SIP/2.0 ~Via: SIP/2.0/TCP 192.168.0.25:65001;branch=z9hG4bK-d8754z-0478310a3b74b930-1---d8754z-;rport ~Max-Forwards: 70 ~Contact: <sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001;transport=tcp> ~To: "+49123456789"<sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@SWYXSRV> ~From: "+49123456789"<sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@SWYXSRV>;tag=0667f373 ~Call-ID: NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg. ~CSeq: 1279365010 REGISTER ~Expires: 0 ~Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE ~User-Agent: Swyx LinkMgr/10.30.0.81 ~Content-Length: 0 ~ ~ 23 09:01:23.326 00101c Inf2 SIP 01292B70 00000000 Transport.cxx:213 () Adding message to tx buffer to: [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=0 ] 23 09:01:23.326 00101c Inf2 SIP 01292B70 00000000 TcpBaseTransport.cxx:141 () Processing write for [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=0 ] 23 09:01:23.326 00101c Inf2 SIP 01292B70 00000000 ConnectionManager.cxx:72 () Found connection for tuple [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=0 ] 23 09:01:23.329 00101c Inf2 SIP 01292B70 00000000 ConnectionBase.cxx:566 () Creating buffer for CONN_BASE: 053F45A8 [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=1656 ] 23 09:01:23.329 00101c Inf2 SIP 01292B70 00000000 ConnectionBase.cxx:102 () In State: NewMessage 23 09:01:23.329 00101c Inf2 SIP 01292B70 00000000 ConnectionBase.cxx:132 () ConnectionBase::process setting source [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=1656 ] 23 09:01:23.329 00101c Inf2 SIP 01292B70 00000000 Transport.cxx:287 () incoming from: [ V4 192.168.0.25:5060 TCP target domain=192.168.0.25 mFlowKey=1656 ] 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 Transport.cxx:288 () ~ ~SIP/2.0 200 OK ~Via: SIP/2.0/TCP 192.168.0.25:65001;branch=z9hG4bK-d8754z-0478310a3b74b930-1---d8754z-;rport=49535 ~Contact: <sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001;transport=tcp>;expires=0 ~To: "+49123456789"<sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@SWYXSRV>;tag=c273a70b ~From: "+49123456789"<sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@SWYXSRV>;tag=0667f373 ~Call-ID: NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg. ~CSeq: 1279365010 REGISTER ~Allow: REGISTER, INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE ~Date: Mon, 23 Jan 2017 08:01:23 GMT ~User-Agent: Swyx IpPbxSrv/10.30.0.273 ~Content-Length: 0 ~ ~ 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 ConnectionManager.cxx:267 () ConnectionManager::process() read=671 23 09:01:23.330 00101c Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for SipResp: 200 tid=0478310a3b74b930 cseq=REGISTER contact=%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001 / 1279365010 from(wire) -> tid=0478310a3b74b930 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:23.330 00101c Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: SipResp: 200 tid=0478310a3b74b930 cseq=REGISTER contact=%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001 / 1279365010 from(wire) 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 DnsResult.cxx:199 () Whitelisting 192.168.0.25(1): 192.168.0.25 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 RRVip.cxx:128 () updating an existing vip: 192.168.0.25 with 192.168.0.25 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 TransactionState.cxx:2155 () Send to default TU: SipResp: 200 tid=0478310a3b74b930 cseq=REGISTER contact=%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001 / 1279365010 from(wire) 23 09:01:23.330 00101c Inf2 SIP 01292B70 00000000 SipStack.cxx:516 () RECV: SipResp: 200 tid=0478310a3b74b930 cseq=REGISTER contact=%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001 / 1279365010 from(wire) 23 09:01:23.330 00101c Inf3 SwSIP 05309F00 00000000 SwSIPMessage::SwSIPMessage () 23 09:01:23.330 00101c Inf3 SwSIP 0127F9B0 00000000 SwSIPReceiver::HandleSipMessage () Start handling of received SIP message... 23 09:01:23.330 00101c Inf3 SwSIP 01292528 00000000 SwSIPEndp::ForwardRespToActReg () Call-ID: NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg. 23 09:01:23.330 00101c Info SwSIP 01292528 00000000 SwSIPEndp::GetActRegByCallId () Search for registration with Call-ID 'NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg.' ... 23 09:01:23.331 00101c Info SwSIP 01292528 00000000 SwSIPEndp::GetActRegByCallId () Found registration with Call-ID 'NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg.' in list of registrations. 23 09:01:23.331 00101c Info SwSIP 01292528 00000000 SwSIPEndp::ForwardRespToActReg () RECV SIP 200 OK 23 09:01:23.331 00101c Inf3 SwSIPReg 07400A98 00000000 SwSIPActRegSipMsgEvent::ctor () 23 09:01:23.331 00101c Info EventQueue 0549AB38 00000000 SEventQueue::QueueEvent () pEvent=07400A98 type=2 name=evt200Received 23 09:01:23.331 00101c Inf3 SwSIPReg 0549AB38 00000000 SwSIPActReg::OnThreadStarted () 23 09:01:23.331 004f2c Info EventQueue 0549AB38 00000000 SEventQueue::Process () pEvent=07400A98 type=2 name=evt200Received 23 09:01:23.331 004f2c Info SwSIPReg 0549AB38 00000000 SwSIPActReg::ActionCheckExpiresHeader () evt200Received in state RegisteredRegisterSent 23 09:01:23.331 004f2c Info SwSIPReg 0549AB38 00000000 SwSIPActReg::ActionCheckExpiresHeader () Registration response 'OK' 23 09:01:23.331 004f2c Info SwSIPReg 0549AB38 00000000 SwSIPActReg::ActionCheckExpiresHeader () last register was sent to logoff from server -> go unregistered! 23 09:01:23.331 004f2c Info SwSIPReg 0549AB38 00000000 SFsm::OnProcessEvent () RegisteredRegisterSent evt200Received Result: 1 NewSt: Unregistered 23 09:01:23.331 004f2c Info SwSIPReg 0549AB38 00000000 SwSIPActReg::EnterStateUnregistered (1) 23 09:01:23.331 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri '%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@swyxsrv' ... 23 09:01:23.331 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Erased registration with AOR or URI'%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@swyxsrv' from list of registrations! 23 09:01:23.331 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri '%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001' ... 23 09:01:23.332 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Erased registration with AOR or URI'%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001' from list of registrations! 23 09:01:23.332 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri 'sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001;transport=tcp' ... 23 09:01:23.332 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByAorOrUri () Could not find registration with AOR or Uri 'sip:%7B286EKBFB-0BC7-4A28-AB36-EB77F8ED8250%7D@192.168.0.25:65001;transport=tcp' in list of registrations! 23 09:01:23.332 004f2c Info RegCtrl 05327C40 00200000 SLinkDevice::OnUnregisterLink () 23 09:01:23.332 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::OnUnregisterLink (05327C40) type: Link 23 09:01:23.332 004f2c Info EventQueue 0547B6D8 00200000 SEventQueue::QueueEvent () pEvent=05358628 type=7 name=evtOnUnregisterLocalSide 23 09:01:23.332 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByCallId () Search for registration with Call-ID 'NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg.' ... 23 09:01:23.332 004f2c Info SwSIP 01292528 00000000 SwSIPEndp::EraseActRegByCallId () Erased registration with Call-ID 'NDgwZmIzN2FiMjhhNGU0Y2FjY2VkNmYxNWZiMTU1ODg.' from list of registrations! 23 09:01:23.332 0020ac Info EventQueue 0547B6D8 00200000 SEventQueue::Process () pEvent=05358628 type=7 name=evtOnUnregisterLocalSide 23 09:01:23.332 004f2c Inf3 SwSIP 05309F00 00000000 SwSIPMessage::~SwSIPMessage () 23 09:01:23.332 0020ac Info RegCtrl 0547B6D8 00200000 SFsm::OnProcessEvent () LocalSideUnregRequested evtOnUnregisterLocalSide Result: 0 NewSt: BothSidesUnregistered 23 09:01:23.332 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::ReleaseLinkDevice () 23 09:01:23.332 004f2c Inf3 SwSIPReg 07400A98 00000000 SwSIPActRegSipMsgEvent::dtor () 23 09:01:23.332 0020ac Info SwSIP 01292528 00000000 SwSIPEndp::DeregisterLinkDevice () 23 09:01:23.332 004f2c Inf3 SwSIPReg 0549AB38 00000000 SwSIPActReg::OnThreadStopped () 23 09:01:23.332 0020ac Inf3 SwSIPReg 0129D0C0 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0549AB38 00000000 SwSIPActReg::~SwSIPActReg () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0549AB38 00000000 SFsm::~SFsm () 23 09:01:23.333 0020ac Info EventQueue 0549AB38 00000000 SEventQueue::~SEventQueue () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129D0C0 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Info EventQueue 05327F04 00000000 SEventQueue::~SEventQueue () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129D0C0 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129D0C0 00000000 SwSIPLink::~SwSIPLink () 23 09:01:23.333 0020ac Inf3 SwTCL 0129D0C0 00000000 SBaseDevice::~SBaseDevice () 23 09:01:23.333 0020ac Inf3 SwTCL 05327C40 00000000 STclDevice::~STclDevice () 23 09:01:23.333 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::ReleaseLinkDevice () 23 09:01:23.333 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::DeregisterLinkDevice () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129C420 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::~SwSIPActReg () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SFsm::~SFsm () 23 09:01:23.333 0020ac Info EventQueue 0549C4E8 00000000 SEventQueue::~SEventQueue () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129C420 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Info EventQueue 05327B5C 00000000 SEventQueue::~SEventQueue () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129C420 00000000 SwSIPLink::SetTclDevice () 23 09:01:23.333 0020ac Inf3 SwSIPReg 0129C420 00000000 SwSIPLink::~SwSIPLink () 23 09:01:23.333 0020ac Inf3 SwTCL 0129C420 00000000 SBaseDevice::~SBaseDevice () 23 09:01:23.333 0020ac Inf3 SwTCL 05327898 00000000 STclDevice::~STclDevice () 23 09:01:23.333 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::EnterUnregistered () use fallback: no 23 09:01:23.583 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer E1 500 -> tid=b9469d3444631c40 [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:23.583 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer E1 500 23 09:01:23.698 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer E1 500 -> tid=331143336260b74f [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:23.698 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer E1 500 23 09:01:23.760 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:23.760 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () no keep alive necessary (write done), refreshInterval=10.000 23 09:01:24.881 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0271DB18 type=4294967294 name=SEventEQTimeout 23 09:01:24.882 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0271DB18 type=4294967294 name=SEventEQTimeout 23 09:01:24.882 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:28.193 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer K 5000 -> tid=b9469d3444631c40 [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:28.193 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer K 5000 23 09:01:28.320 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer K 5000 -> tid=331143336260b74f [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:28.320 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer K 5000 23 09:01:28.756 0020ac Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:29.893 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0271DE38 type=4294967294 name=SEventEQTimeout 23 09:01:29.969 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0271DE38 type=4294967294 name=SEventEQTimeout 23 09:01:29.969 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:33.762 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:34.841 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0539C9A8 type=4294967294 name=SEventEQTimeout 23 09:01:34.841 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0539C9A8 type=4294967294 name=SEventEQTimeout 23 09:01:34.841 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:38.763 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:39.886 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=05358528 type=4294967294 name=SEventEQTimeout 23 09:01:39.888 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=05358528 type=4294967294 name=SEventEQTimeout 23 09:01:39.888 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:43.837 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:44.924 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=053585C8 type=4294967294 name=SEventEQTimeout 23 09:01:44.924 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=053585C8 type=4294967294 name=SEventEQTimeout 23 09:01:44.924 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:48.816 0020ac Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:49.847 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0271DB38 type=4294967294 name=SEventEQTimeout 23 09:01:49.847 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0271DB38 type=4294967294 name=SEventEQTimeout 23 09:01:49.847 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:53.416 000f78 Info EventQueue 0547B6D8 00200000 SEventQueue::QueueEvent () pEvent=0537B290 type=8 name=evtTimeout 23 09:01:53.416 004f2c Info EventQueue 0547B6D8 00200000 SEventQueue::Process () pEvent=0537B290 type=8 name=evtTimeout 23 09:01:53.416 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0535B730 type=0 name=IpAddressChangeCheck 23 09:01:53.416 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::auxRegisterRemoteSide () register at server "tel.t-online.de" 23 09:01:53.416 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () 23 09:01:53.416 0020ac Info EventQueue 012BE898 00000000 SSubEventQueue::ProcessEvent () pEvent=0535B730 type=0 name=IpAddressChangeCheck 23 09:01:53.416 004f2c Alws RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () Remote Link type: SIPLink 23 09:01:53.416 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () public SIP link - don't resolve remote server address ! 23 09:01:53.416 0020ac Info FrameWork 012BE898 00000000 SDeSerializer::OnProcessEvent () IpAddressChangeCheck for link: +49123456789 23 09:01:53.416 004f2c Info SwSIP 01292B30 00000000 SwSIPEndp::CreateLinkDevice () Local IP address: Ip:192.168.0.25:65002 23 09:01:53.416 0020ac Info FrameWork 012BE898 00000000 SLinkMgrCfg::FindLink () name='+49123456789' 23 09:01:53.416 004f2c Inf3 SwTCL 0129C748 00000000 SBaseDevice::SBaseDevice () 23 09:01:53.417 004f2c Info EventQueue 0549C0A0 00000000 SEventQueue::SEventQueue () 23 09:01:53.417 0020ac Info FrameWork 00000000 00000000 ::GetMyIpAddressForLocalSide () my IP address for local side:192.168.0.25 23 09:01:53.417 004f2c Inf3 SwSIPReg 0549C0A0 00000000 SFsm::SFsm () 23 09:01:53.417 004f2c Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::SwSIPActReg () transport type: udp 23 09:01:53.417 004f2c Inf3 SwSIP 01292B30 00000000 SwSIPEndp::GetCall () Search for call with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' ... 23 09:01:53.417 004f2c Inf3 SwSIP 01292B30 00000000 SwSIPEndp::GetCall () Could not find call with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' in list of calls! 23 09:01:53.417 004f2c Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::SwSIPActReg (, ) with call Id: NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU. 23 09:01:53.417 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::SwSIPLink (Ip:192.168.0.25:65002, SIPLink, Ip:<not set>, +49123456789, 88393608) 23 09:01:53.417 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::SetTclDevice () 23 09:01:53.417 004f2c Inf3 SwTCL 05327898 00000000 STclDevice::STclDevice () 23 09:01:53.417 004f2c Info EventQueue 05327B5C 00000000 SEventQueue::SEventQueue () 23 09:01:53.417 004f2c Info SwTCL 05327898 00000000 STclDevice::RegisterLink () 0129C748 23 09:01:53.417 004f2c Info SwSIPReg 0129C748 00000000 SwSIPLink::RegisterLink () Trying to register... 23 09:01:53.417 004f2c Info EventQueue 0549C0A0 00000000 SEventQueue::QueueEvent () pEvent=0533C648 type=0 name=evtRegister 23 09:01:53.417 004f2c Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::OnThreadStarted () 23 09:01:53.417 004f2c Info RegCtrl 0547B6D8 00200000 SFsm::OnProcessEvent () BothSidesUnregistered evtTimeout Result: 0 NewSt: RemoteSideRegRequested 23 09:01:53.417 001f50 Info EventQueue 0549C0A0 00000000 SEventQueue::Process () pEvent=0533C648 type=0 name=evtRegister 23 09:01:53.417 001f50 Info SwSIPReg 0549C0A0 00000000 SwSIPActReg::LeaveStateUnregistered () 23 09:01:53.417 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Search for registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' ... 23 09:01:53.417 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Could not find registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' in list of registrations! 23 09:01:53.417 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByCallId () Added registration 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' to list of registrations! 23 09:01:53.418 001f50 Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::ActionSendInitialRegister () 23 09:01:53.418 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::SDnsQuery () target=tel.t-online.de 23 09:01:53.419 0020ac Info FrameWork 00000000 00000000 ::GetMyIpAddressForRemoteSide () My best interface IP address to contact remote side: 192.168.0.25 23 09:01:53.425 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupNAPTR () tel.t-online.de 23 09:01:53.425 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupSRV () _sips._tcp.tel.t-online.de 23 09:01:53.425 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupSRV () _sips._udp.tel.t-online.de 23 09:01:53.425 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupSRV () _sip._tcp.tel.t-online.de 23 09:01:53.426 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupSRV () _sip._udp.tel.t-online.de 23 09:01:53.426 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupARecords () tel.t-online.de 23 09:01:53.426 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupARecords () sip.tel.t-online.de 23 09:01:53.426 001f50 Inf3 DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Wait for DNS result, resolving: tel.t-online.de 23 09:01:53.426 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::WaitForResult () 23 09:01:53.426 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::WaitForResult () States=0/0/0 timeToWait=5.000 timeDiff=0.000 23 09:01:53.460 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:01:53.460 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () status=0 23 09:01:53.460 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:01:53.460 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: ims.voip.t-ipnet.de 23 09:01:53.460 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupNAPTR () ims.voip.t-ipnet.de 23 09:01:53.462 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:01:53.462 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () status=4 23 09:01:53.462 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SrvCount= 3 status=4 23 09:01:53.462 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:01:53.462 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () No SRV records for tel.t-online.de 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () status=4 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SrvCount= 2 status=4 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () No SRV records for tel.t-online.de 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () status=0 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SrvCount= 1 status=0 23 09:01:53.559 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () Adding SRV record (no NAPTR): Key=_sip._udp.tel.t-online.de, Transp=1, Prio=0, Wght=5, Port=5060, Target=ims001.voip.t-ipnet.de 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () Adding SRV record (no NAPTR): Key=_sip._udp.tel.t-online.de, Transp=1, Prio=1, Wght=5, Port=5060, Target=ims002.voip.t-ipnet.de 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () status=4 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SrvCount= 0 status=4 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () status=0 23 09:01:53.560 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: ims001.voip.t-ipnet.de 23 09:01:53.560 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupNAPTR () ims001.voip.t-ipnet.de 23 09:01:53.696 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:01:53.696 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () status=0 23 09:01:53.696 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:01:53.696 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: b-epp-001.isp.t-ipnet.de 23 09:01:53.696 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::lookupNAPTR () b-epp-001.isp.t-ipnet.de 23 09:01:53.778 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:01:53.778 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () status=0 23 09:01:53.778 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processNAPTR () Adding NAPTR record: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= replace=_sip._udp.tel.t-online.de 23 09:01:53.779 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:54.871 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0271DDD8 type=4294967294 name=SEventEQTimeout 23 09:01:54.872 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0271DDD8 type=4294967294 name=SEventEQTimeout 23 09:01:54.872 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:01:58.470 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::WaitForResult () leaving while loop, State=1/1/0 timeToWait=5.000 timeDiff=5.047 23 09:01:58.470 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Naptr: Key=b-epp-001.isp.t-ipnet.de, Order=0, Pref=0, Flags=s, Service=SIP+D2U, Regex=, Repl=_sip._udp.tel.t-online.de 23 09:01:58.470 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Found matchin SRV Record: Key=_sip._udp.tel.t-online.de, Transp=1, Prio=0, Wght=5, Port=5060, Target=ims001.voip.t-ipnet.de 23 09:01:58.470 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::SDnsQuery () target=ims001.voip.t-ipnet.de 23 09:01:58.471 000f78 Info EventQueue 0547B6D8 00200000 SEventQueue::QueueEvent () pEvent=0539DAB0 type=8 name=evtTimeout 23 09:01:58.471 004f2c Info EventQueue 0547B6D8 00200000 SEventQueue::Process () pEvent=0539DAB0 type=8 name=evtTimeout 23 09:01:58.471 004f2c Info SwTCL 05327898 00000000 STclDevice::UnregisterLink () 0129C748 23 09:01:58.471 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::UnregisterLink () 23 09:01:58.471 004f2c Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::Deregister () 23 09:01:58.471 004f2c Info EventQueue 0549C0A0 00000000 SEventQueue::QueueEvent () pEvent=05309EB8 type=1 name=evtUnregister 23 09:01:58.471 004f2c Info RegCtrl 0547B6D8 00200000 SFsm::OnProcessEvent () RemoteSideRegRequested evtTimeout Result: 0 NewSt: BothSidesUnregistered 23 09:01:58.471 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::ReleaseLinkDevice () 23 09:01:58.471 004f2c Info SwSIP 01292B30 00000000 SwSIPEndp::DeregisterLinkDevice () 23 09:01:58.471 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::SetTclDevice () 23 09:01:58.471 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::SetTclDevice () 23 09:01:58.471 004f2c Info EventQueue 05327B5C 00000000 SEventQueue::~SEventQueue () 23 09:01:58.471 004f2c Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::SetTclDevice () 23 09:01:58.471 004f2c Inf3 SwTCL 05327898 00000000 STclDevice::~STclDevice () 23 09:01:58.471 004f2c Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::EnterUnregistered () use fallback: no 23 09:01:58.478 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::lookupARecords () ims001.voip.t-ipnet.de 23 09:01:58.478 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::lookupARecords () sip.ims001.voip.t-ipnet.de 23 09:01:58.479 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::WaitForResult () 23 09:01:58.479 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::WaitForResult () States=2/2/0 timeToWait=5.000 timeDiff=0.000 23 09:01:58.550 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::aresHostCallback () Received A result for ims001.voip.t-ipnet.de 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::processHost () ACount= 1 status=0 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::processHost () DNS A lookup canonical name: b-epp-001.isp.t-ipnet.de 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::processHost () Adding 217.0.23.100 to result set 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::aresHostCallback () Received A result for ims001.voip.t-ipnet.de 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::processHost () ACount= 0 status=4 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::processHost () Failed async A query: Domain name not found 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::WaitForResult () leaving while loop, State=2/2/2 timeToWait=5.000 timeDiff=0.078 23 09:01:58.551 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Resolved SRV Record via A Record: 217.0.23.100 23 09:01:58.551 001f50 Info DnsQuery 073D68D0 00000000 SDnsQuery::~SDnsQuery () 23 09:01:58.551 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Lookup best interface for target address: 217.0.23.100 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::GetPeerAndOwnAddress Best interface:Intel(R) Ethernet Connection I217-LM, 192.168.0.25 for target: tel.t-online.de, 217.0.23.100 23 09:01:58.554 001f50 Info SwSIPReg 0549C0A0 00000000 SwSIPActReg::ActionSendInitialRegister () refresh stun with ip: 217.0.23.100 resolved via NAPTR/SRV/A 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresHostCallback () Received A result for tel.t-online.de 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processHost () ACount= 1 status=16 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processHost () Failed async A query: Destructing 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::aresHostCallback () Received A result for tel.t-online.de 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processHost () ACount= 0 status=16 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::processHost () Failed async A query: Destructing 23 09:01:58.554 001f50 Info DnsQuery 0533F448 00000000 SDnsQuery::~SDnsQuery () 23 09:01:58.554 001f50 Info STUN 012B40D0 00000000 SSTUNSocket::AddDestForRefresh () 217.0.23.100:5060 23 09:01:58.555 001f50 Info SwSIPReg 0549C0A0 00000000 SwSIPActReg::MakeRegisterRequest () use SIPConnect v1.1: no 23 09:01:58.555 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (tel.t-online.de) Converted to string: tel.t-online.de 23 09:01:58.555 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (+49123456789) Converted to string: +49123456789 23 09:01:58.555 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (tel.t-online.de) Converted to string: tel.t-online.de 23 09:01:58.555 001f50 Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::MakeRegisterRequest () put STUN address/port to Via-header:Ip:217.1.121.16:58186 23 09:01:58.555 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByAorOrUri () Added registration '+49123456789@tel.t-online.de' to list of registrations! 23 09:01:58.556 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByAorOrUri () Added registration '+49123456789@217.1.121.16:58186' to list of registrations! 23 09:01:58.556 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddRecogUri () added +49123456789 - +49123456789 @ --> 0129C748 23 09:01:58.556 001f50 Info SwSIPReg 0549C0A0 00000000 SwSIPActReg::ActionSendInitialRegister () SEND SIP REGISTER 23 09:01:58.556 001f50 Inf2 SIP 01292B70 00000000 SipStack.cxx:333 () SEND: SipReq: REGISTER tel.t-online.de tid=461a3f440c02e944 cseq=REGISTER contact=+49123456789@217.1.121.16:58186 / 1279429399 from(tu) 23 09:01:58.556 001f50 Info SwSIPReg 0549C0A0 00000000 SFsm::OnProcessEvent () Unregistered evtRegister Result: 0 NewSt: UnregisteredRegisterSent 23 09:01:58.556 001f50 Info EventQueue 0549C0A0 00000000 SEventQueue::Process () pEvent=05309EB8 type=1 name=evtUnregister 23 09:01:58.556 001f50 Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::NoAction () 23 09:01:58.556 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:391 () No matching transaction for SipReq: REGISTER tel.t-online.de tid=461a3f440c02e944 cseq=REGISTER contact=+49123456789@217.1.121.16:58186 / 1279429399 from(tu) 23 09:01:58.556 001f50 Info SwSIPReg 0549C0A0 00000000 SFsm::OnProcessEvent () UnregisteredRegisterSent evtUnregister Result: 0 NewSt: RegAbortedWaitingFor200 23 09:01:58.556 001f50 Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::OnThreadStopped () 23 09:01:58.556 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:58 () Creating new TransactionState: tid=461a3f440c02e944 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:58.556 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: SipReq: REGISTER tel.t-online.de tid=461a3f440c02e944 cseq=REGISTER contact=+49123456789@217.1.121.16:58186 / 1279429399 from(tu) 23 09:01:58.557 001020 Inf2 SIP 01292B70 00000000 TimerQueue.cxx:85 () Adding timer: Timer F tid=461a3f440c02e944 ms=32000 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:2043 () sendToWire with no dns result: tid=461a3f440c02e944 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:58.557 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:415 () Looking up dns entries for sip:tel.t-online.de 23 09:01:58.557 001020 Inf2 SIP 01292B70 00000000 DnsResult.cxx:207 () DnsResult::lookup sip:tel.t-online.de 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:tel.t-online.de NAPTR 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:421 () tel.t-online.de mapped to CNAME b-epp-001.isp.t-ipnet.de 23 09:01:58.557 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:68 () b-epp-001.isp.t-ipnet.de (NAPTR)--> o=0 p=0 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1293 () Received NAPTR result for: sip: target=tel.t-online.de 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1294 () DnsResult::onDnsResult() 0 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1199 () Adding NAPTR record: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= -> replacement=_sip._udp.tel.t-online.de 23 09:01:58.557 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1206 () Picked preferred: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= -> replacement=_sip._udp.tel.t-online.de 23 09:01:58.558 001020 Info SIP 01292B70 00000000 DnsResult.cxx:1227 () Doing SRV lookup of _sip._udp.tel.t-online.de 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:_sip._udp.tel.t-online.de SRV 23 09:01:58.558 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:62 () _sip._udp.tel.t-online.de (SRV) --> p=1 w=5 ims002.voip.t-ipnet.de:5060_sip._udp.tel.t-online.de (SRV) --> p=0 w=5 ims001.voip.t-ipnet.de:5060 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:982 () Received SRV result for: tel.t-online.de 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:985 () DnsResult::onDnsResult() 0 status=0 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:503 () Priming [key=_sip._udp.tel.t-online.de t=UDP p=0 w=5 port=5060 target=ims001.voip.t-ipnet.de, key=_sip._udp.tel.t-online.de t=UDP p=1 w=5 port=5060 target=ims002.voip.t-ipnet.de] 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:621 () cumulative weight = 5 selected=4 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:656 () SRV: [key=_sip._udp.tel.t-online.de t=UDP p=1 w=5 port=5060 target=ims002.voip.t-ipnet.de] 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:511 () Primed with SRV=key=_sip._udp.tel.t-online.de t=UDP p=0 w=5 port=5060 target=ims001.voip.t-ipnet.de 23 09:01:58.558 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:515 () No A or AAAA record for ims001.voip.t-ipnet.de in additional records 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:ims001.voip.t-ipnet.de A 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:421 () ims001.voip.t-ipnet.de mapped to CNAME b-epp-001.isp.t-ipnet.de 23 09:01:58.559 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:44 () b-epp-001.isp.t-ipnet.de(A)--> 217.0.23.100 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:772 () Received dns result for: tel.t-online.de 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:773 () DnsResult::onDnsResult() 0 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:795 () Adding [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] to result set 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:1870 () tid=461a3f440c02e944 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] got DNS result: tel.t-online.de --> [[ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ]] 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:187 () Returning next dns entry: [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] 23 09:01:58.559 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:1911 () Unreliable transport: tid=461a3f440c02e944 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:58.559 001020 Inf2 SIP 01292B70 00000000 TimerQueue.cxx:85 () Adding timer: Timer E1 tid=461a3f440c02e944 ms=500 23 09:01:58.559 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:522 () hint provided by app: SIP/2.0/ 217.1.121.16:58186;branch=z9hG4bK-d8754z-461a3f440c02e944-1---d8754z-;rport 23 09:01:58.559 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1207 () findTransportBySource([ V4 217.1.121.16:58186 UDP target domain=unspecified mFlowKey=0 ]) 23 09:01:58.560 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1261 () findTransport (any interface) => Transport: [ V4 0.0.0.0:65002 UDP target domain=unspecified mFlowKey=0 ] 23 09:01:58.560 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1039 () Transmitting to [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] tlsDomain= via [ V4 217.1.121.16:58186 UDP target domain=unspecified mFlowKey=0 ] ~ ~REGISTER sip:tel.t-online.de SIP/2.0 ~Via: SIP/2.0/UDP 217.1.121.16:58186;branch=z9hG4bK-d8754z-461a3f440c02e944-1---d8754z-;rport ~Max-Forwards: 70 ~Contact: <sip:+49123456789@217.1.121.16:58186> ~To: <sip:+49123456789@tel.t-online.de> ~From: <sip:+49123456789@tel.t-online.de>;tag=0774d478 ~Call-ID: NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU. ~CSeq: 1279429399 REGISTER ~Expires: 480 ~Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE ~User-Agent: Swyx LinkMgr/10.30.0.81 ~Content-Length: 0 ~ ~ 23 09:01:58.560 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:213 () Adding message to tx buffer to: [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] 23 09:01:58.723 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received SIP data at STUN socket, len=567 23 09:01:58.723 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:287 () incoming from: [ V4 217.0.23.100:5060 UDP target domain=unspecified mFlowKey=0 ] 23 09:01:58.723 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:288 () ~ ~SIP/2.0 401 Unauthorized ~Via: SIP/2.0/UDP 217.1.121.16:58186;received=217.1.121.16;rport=49253;branch=z9hG4bK-d8754z-461a3f440c02e944-1---d8754z- ~Service-Route: <sip:217.0.23.100:5060;transport=udp;lr> ~To: <sip:+49123456789@tel.t-online.de>;tag=h7g4Esbg_c0160e6f0790e6e740c6d6c16d02876f ~From: <sip:+49123456789@tel.t-online.de>;tag=0774d478 ~Call-ID: NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU. ~CSeq: 1279429399 REGISTER ~WWW-Authenticate: Digest realm="tel.t-online.de",nonce="3E945DFE7DB88558000000007557F42E",algorithm=MD5,qop="auth" ~Content-Length: 0 ~ ~ 23 09:01:58.724 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for SipResp: 401 tid=461a3f440c02e944 cseq=REGISTER / 1279429399 from(wire) -> tid=461a3f440c02e944 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:58.724 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: SipResp: 401 tid=461a3f440c02e944 cseq=REGISTER / 1279429399 from(wire) 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 DnsResult.cxx:199 () Whitelisting b-epp-001.isp.t-ipnet.de(35): _sip._udp.tel.t-online.de 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 RRVip.cxx:128 () updating an existing vip: _sip._udp.tel.t-online.de with _sip._udp.tel.t-online.de 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 DnsResult.cxx:199 () Whitelisting _sip._udp.tel.t-online.de(33): ims001.voip.t-ipnet.de:5060 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 RRVip.cxx:128 () updating an existing vip: ims001.voip.t-ipnet.de:5060 with ims001.voip.t-ipnet.de:5060 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 DnsResult.cxx:199 () Whitelisting tel.t-online.de(1): 217.0.23.100 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 RRVip.cxx:128 () updating an existing vip: 217.0.23.100 with 217.0.23.100 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 TransactionState.cxx:2155 () Send to default TU: SipResp: 401 tid=461a3f440c02e944 cseq=REGISTER / 1279429399 from(wire) 23 09:01:58.724 001020 Inf2 SIP 01292B70 00000000 TimerQueue.cxx:85 () Adding timer: Timer K tid=461a3f440c02e944 ms=5000 23 09:01:58.725 001020 Inf2 SIP 01292B70 00000000 SipStack.cxx:516 () RECV: SipResp: 401 tid=461a3f440c02e944 cseq=REGISTER / 1279429399 from(wire) 23 09:01:58.725 001020 Inf3 SwSIP 05319CB0 00000000 SwSIPMessage::SwSIPMessage () 23 09:01:58.725 001020 Inf3 SwSIP 0127FD20 00000000 SwSIPReceiver::HandleSipMessage () Start handling of received SIP message... 23 09:01:58.725 001020 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ForwardRespToActReg () Call-ID: NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU. 23 09:01:58.725 001020 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Search for registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' ... 23 09:01:58.725 001020 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Found registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' in list of registrations. 23 09:01:58.725 001020 Info SwSIP 01292B30 00000000 SwSIPEndp::ForwardRespToActReg () RECV SIP 401 Unauthorized 23 09:01:58.725 001020 Inf3 SwSIPReg 0539C7E8 00000000 SwSIPActRegSipMsgEvent::ctor () 23 09:01:58.725 001020 Info EventQueue 0549C0A0 00000000 SEventQueue::QueueEvent () pEvent=0539C7E8 type=3 name=evt401or407Received 23 09:01:58.725 001020 Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::OnThreadStarted () 23 09:01:58.725 0020ac Info EventQueue 0549C0A0 00000000 SEventQueue::Process () pEvent=0539C7E8 type=3 name=evt401or407Received 23 09:01:58.725 0020ac Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::NoAction () 23 09:01:58.725 0020ac Info SwSIPReg 0549C0A0 00000000 SFsm::OnProcessEvent () RegAbortedWaitingFor200 evt401or407Received Result: 0 NewSt: Unregistered 23 09:01:58.725 0020ac Info SwSIPReg 0549C0A0 00000000 SwSIPActReg::EnterStateUnregistered (1) 23 09:01:58.725 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri '+49123456789@tel.t-online.de' ... 23 09:01:58.725 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Erased registration with AOR or URI'+49123456789@tel.t-online.de' from list of registrations! 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri '+49123456789@217.1.121.16:58186' ... 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Erased registration with AOR or URI'+49123456789@217.1.121.16:58186' from list of registrations! 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Search for registration with AOR or Uri 'sip:+49123456789@217.1.121.16:58186' ... 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByAorOrUri () Could not find registration with AOR or Uri 'sip:+49123456789@217.1.121.16:58186' in list of registrations! 23 09:01:58.726 0020ac Inf3 SwSIP 01292B30 00000000 SwSIPEndp::RemoveRecogUri () remove all entries with pDev=0129C748 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::RemoveRecogUri () erased +49123456789 - +49123456789 pDev=0129C748 23 09:01:58.726 0020ac Info STUN 012B40D0 00000000 SSTUNSocket::RemoveDestForRefresh () 217.0.23.100:5060 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByCallId () Search for registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' ... 23 09:01:58.726 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::EraseActRegByCallId () Erased registration with Call-ID 'NmY4NDQwZmI5MzdiYjc3OWU3YzdiOWZjNWIyNDQxZTU.' from list of registrations! 23 09:01:58.726 0020ac Inf3 SwSIP 05319CB0 00000000 SwSIPMessage::~SwSIPMessage () 23 09:01:58.726 0020ac Inf3 SwSIPReg 0539C7E8 00000000 SwSIPActRegSipMsgEvent::dtor () 23 09:01:58.726 0020ac Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::OnThreadStopped () 23 09:01:58.726 0020ac Inf3 SwSIPReg 0129C748 00000000 SwSIPLink::~SwSIPLink () 23 09:01:58.726 0020ac Inf3 SwTCL 0129C748 00000000 SBaseDevice::~SBaseDevice () 23 09:01:58.727 0020ac Inf3 SwSIPReg 0549C0A0 00000000 SwSIPActReg::~SwSIPActReg () 23 09:01:58.727 0020ac Inf3 SwSIPReg 0549C0A0 00000000 SFsm::~SFsm () 23 09:01:58.727 0020ac Info EventQueue 0549C0A0 00000000 SEventQueue::~SEventQueue () 23 09:01:58.753 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:01:58.753 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () no keep alive necessary (write done), refreshInterval=10.000 23 09:01:59.083 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer E1 500 -> tid=461a3f440c02e944 [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:01:59.083 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer E1 500 23 09:01:59.831 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0539C748 type=4294967294 name=SEventEQTimeout 23 09:01:59.831 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0539C748 type=4294967294 name=SEventEQTimeout 23 09:01:59.831 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:02:03.733 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:356 () Found matching transaction for Timer: Timer K 5000 -> tid=461a3f440c02e944 [ ClientNonInvite/Completed unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:02:03.733 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: Timer: Timer K 5000 23 09:02:03.794 0020ac Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:02:04.905 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=053068B0 type=4294967294 name=SEventEQTimeout 23 09:02:04.905 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=053068B0 type=4294967294 name=SEventEQTimeout 23 09:02:04.905 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:02:08.814 0020ac Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:02:09.905 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=053066D0 type=4294967294 name=SEventEQTimeout 23 09:02:10.002 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=053066D0 type=4294967294 name=SEventEQTimeout 23 09:02:10.002 004f2c Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:02:13.754 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:02:14.881 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=053067F0 type=4294967294 name=SEventEQTimeout 23 09:02:14.883 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=053067F0 type=4294967294 name=SEventEQTimeout 23 09:02:14.883 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () rediscover external address 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::DiscoverExternalAddress () use stunAddr=217.0.0.193:3478 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:18.802 004f2c Info STUN 0713F6F4 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:18.802 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:18.802 004f2c Info STUN 0713F834 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:18.830 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:18.830 001020 Info STUN 02AD922C 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:18.830 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received STUN data in foreign context! 23 09:02:18.830 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () put STUN msg into queue, len=56 23 09:02:18.830 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () get STUN msg from queue, because we were signalled, len=1000 rBytesRead=56 23 09:02:18.830 004f2c Info STUN 0713F840 00000000 Read () Setting new size in STUN-Read: size=56 23 09:02:18.830 004f2c Info STUN 0713F840 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:18.830 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::DiscoverExternalAddress () New external IP/Port discovered: Old=217.1.121.16:58186 New=217.1.121.16:45421 23 09:02:18.830 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::DiscoverExternalAddress () external IP:Port=217.1.121.16:45421 23 09:02:18.831 004f2c Inf3 STUN 012B40D0 00000000 SSTUNSocket::OnTimeout () no keep alive necessary (write done), refreshInterval=10.000 23 09:02:19.831 000f78 Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=0539C728 type=4294967294 name=SEventEQTimeout 23 09:02:19.831 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () pEvent=0539C728 type=4294967294 name=SEventEQTimeout 23 09:02:19.831 001f50 Info EventQueue 012A3E30 00000000 SEventQueue::Process () call timeout function for timer 012BEA38 23 09:02:19.831 001f50 Info FrameWork 012BE898 00000000 SLinkMgrCfg::OnSTUNMonitorTimeout () new IP address at external firewall interface - reconnect links Old=217.1.121.16:58186 New=217.1.121.16:45421 23 09:02:19.831 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::DiscoverNatType () use stunAddr=217.0.0.193:3478 23 09:02:19.831 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:19.831 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:19.831 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:19.832 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:19.832 001f50 Info STUN 0281F7D8 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:19.894 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:19.894 001020 Info STUN 02AD922C 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:19.895 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received STUN data in foreign context! 23 09:02:19.895 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () put STUN msg into queue, len=56 23 09:02:19.895 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () get STUN msg from queue, because we were signalled, len=1000 rBytesRead=56 23 09:02:19.895 001f50 Info STUN 0281F7E4 00000000 Read () Setting new size in STUN-Read: size=56 23 09:02:19.895 001f50 Info STUN 0281F7E4 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:19.895 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:19.895 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:19.895 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:19.895 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:19.895 001f50 Info STUN 0281F7CC 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:20.906 001f50 Info STUN 0281F790 00000000 Read () Read failed, handle=1100 23 09:02:20.906 001f50 Info STUN 0281F790 00000000 SSTUNMessage::Poll () no success handle=1100 23 09:02:20.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:20.906 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:20.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:20.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:20.906 001f50 Info STUN 0281F7CC 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:21.906 001f50 Info STUN 0281F790 00000000 Read () Read failed, handle=1100 23 09:02:21.906 001f50 Info STUN 0281F790 00000000 SSTUNMessage::Poll () no success handle=1100 23 09:02:21.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:21.906 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:21.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:21.906 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:21.906 001f50 Info STUN 0281F7CC 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:22.953 001f50 Info STUN 0281F790 00000000 Read () Read failed, handle=1100 23 09:02:22.953 001f50 Info STUN 0281F790 00000000 SSTUNMessage::Poll () no success handle=1100 23 09:02:22.954 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:22.954 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:22.954 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:22.955 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:22.955 001f50 Info STUN 0281F7A8 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:23.087 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:23.087 001020 Info STUN 02AD922C 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:23.087 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received STUN data in foreign context! 23 09:02:23.087 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () put STUN msg into queue, len=56 23 09:02:23.088 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () get STUN msg from queue, because we were signalled, len=1000 rBytesRead=56 23 09:02:23.088 001f50 Info STUN 0281F7B4 00000000 Read () Setting new size in STUN-Read: size=56 23 09:02:23.088 001f50 Info STUN 0281F7B4 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:23.088 001f50 Info STUN 0281F7C0 00000000 IsValidAttributePointer () Invalid STUNMessage: Size=24 GetSize()=20 23 09:02:23.088 001f50 Info STUN 0281F7C0 00000000 GetFirstAttribute () Invalid STUNMessage: GetSize()=20HeaderSize=20 23 09:02:23.088 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:23.088 001f50 Info STUN 0281F650 00000000 Validate () Validate, length=0 msgLength=8 23 09:02:23.088 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: SetQos=1 Addr=217.0.0.193 Port=3478 23 09:02:23.088 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::WriteTo () STUN: (restore) SetQos=1 Addr=217.0.23.100 Port=5060 23 09:02:23.088 001f50 Info STUN 0281F7C0 00000000 Write () Write action, handle=1100 ok=1 bytes=28 23 09:02:23.213 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::IsSTUNData () message type OK 23 09:02:23.213 001020 Info STUN 02AD922C 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:23.213 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received STUN data in foreign context! 23 09:02:23.213 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () put STUN msg into queue, len=56 23 09:02:23.214 001f50 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () get STUN msg from queue, because we were signalled, len=1000 rBytesRead=56 23 09:02:23.214 001f50 Info STUN 0281F79C 00000000 Read () Setting new size in STUN-Read: size=56 23 09:02:23.214 001f50 Info STUN 0281F79C 00000000 Validate () Validate, length=0 msgLength=36 23 09:02:23.214 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::UpdateTransport () update udp transport 23 09:02:23.214 001f50 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:301 () updating transport (STUN) from [ V4 0.0.0.0:58186 UDP target domain=unspecified mFlowKey=0 ] to [ V4 0.0.0.0:45421 UDP target domain=unspecified mFlowKey=0 ] 23 09:02:23.214 001f50 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:307 () erasing 1 elements from mAnyInterfaceTransports 23 09:02:23.214 001f50 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:310 () erasing 1 elements from mAnyPortAnyInterfaceTransports 23 09:02:23.214 001f50 Info FrameWork 012BE898 00000000 SLinkMgrConfig::ReconnectSipLinks () Start reconnecting all SIP links using STUN... 23 09:02:23.214 001f50 Info RegCtrl 0547B6C8 00000000 SRegCtrlFSM::RestartRegistration () 23 09:02:23.214 001f50 Info EventQueue 0547B6D8 00200000 SEventQueue::QueueEvent () pEvent=0537B0B0 type=10 name=evtRestart 23 09:02:23.214 0020ac Info EventQueue 0547B6D8 00200000 SEventQueue::Process () pEvent=0537B0B0 type=10 name=evtRestart 23 09:02:23.214 0020ac Info EventQueue 012A3E30 00000000 SEventQueue::QueueEvent () pEvent=05356188 type=0 name=IpAddressChangeCheck 23 09:02:23.214 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::auxRegisterRemoteSide () register at server "tel.t-online.de" 23 09:02:23.215 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () 23 09:02:23.215 004f2c Info EventQueue 012BE898 00000000 SSubEventQueue::ProcessEvent () pEvent=05356188 type=0 name=IpAddressChangeCheck 23 09:02:23.215 0020ac Alws RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () Remote Link type: SIPLink 23 09:02:23.215 0020ac Info RegCtrl 0547B6C8 00200000 SRegCtrlFSM::CreateLinkDevice () public SIP link - don't resolve remote server address ! 23 09:02:23.215 004f2c Info FrameWork 012BE898 00000000 SDeSerializer::OnProcessEvent () IpAddressChangeCheck for link: +49123456789 23 09:02:23.215 0020ac Info SwSIP 01292B30 00000000 SwSIPEndp::CreateLinkDevice () Local IP address: Ip:192.168.0.25:65002 23 09:02:23.215 004f2c Info FrameWork 012BE898 00000000 SLinkMgrCfg::FindLink () name='+49123456789' 23 09:02:23.215 0020ac Inf3 SwTCL 0129C0F8 00000000 SBaseDevice::SBaseDevice () 23 09:02:23.215 004f2c Info FrameWork 00000000 00000000 ::GetMyIpAddressForLocalSide () my IP address for local side:192.168.0.25 23 09:02:23.215 0020ac Info EventQueue 0549C4E8 00000000 SEventQueue::SEventQueue () 23 09:02:23.215 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SFsm::SFsm () 23 09:02:23.215 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::SwSIPActReg () transport type: udp 23 09:02:23.215 0020ac Inf3 SwSIP 01292B30 00000000 SwSIPEndp::GetCall () Search for call with Call-ID 'OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk.' ... 23 09:02:23.215 0020ac Inf3 SwSIP 01292B30 00000000 SwSIPEndp::GetCall () Could not find call with Call-ID 'OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk.' in list of calls! 23 09:02:23.215 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::SwSIPActReg (, ) with call Id: OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk. 23 09:02:23.215 0020ac Inf3 SwSIPReg 0129C0F8 00000000 SwSIPLink::SwSIPLink (Ip:192.168.0.25:65002, SIPLink, Ip:<not set>, +49123456789, 88393608) 23 09:02:23.215 0020ac Inf3 SwSIPReg 0129C0F8 00000000 SwSIPLink::SetTclDevice () 23 09:02:23.215 0020ac Inf3 SwTCL 05329D28 00000000 STclDevice::STclDevice () 23 09:02:23.215 0020ac Info EventQueue 05329FEC 00000000 SEventQueue::SEventQueue () 23 09:02:23.215 0020ac Info SwTCL 05329D28 00000000 STclDevice::RegisterLink () 0129C0F8 23 09:02:23.215 0020ac Info SwSIPReg 0129C0F8 00000000 SwSIPLink::RegisterLink () Trying to register... 23 09:02:23.215 0020ac Info EventQueue 0549C4E8 00000000 SEventQueue::QueueEvent () pEvent=0533CE60 type=0 name=evtRegister 23 09:02:23.215 0020ac Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::OnThreadStarted () 23 09:02:23.216 0020ac Info RegCtrl 0547B6D8 00200000 SFsm::OnProcessEvent () BothSidesUnregistered evtRestart Result: 0 NewSt: RemoteSideRegRequested 23 09:02:23.216 001f50 Info EventQueue 0549C4E8 00000000 SEventQueue::Process () pEvent=0533CE60 type=0 name=evtRegister 23 09:02:23.216 001f50 Info SwSIPReg 0549C4E8 00000000 SwSIPActReg::LeaveStateUnregistered () 23 09:02:23.216 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Search for registration with Call-ID 'OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk.' ... 23 09:02:23.216 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::GetActRegByCallId () Could not find registration with Call-ID 'OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk.' in list of registrations! 23 09:02:23.216 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByCallId () Added registration 'OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk.' to list of registrations! 23 09:02:23.216 001f50 Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::ActionSendInitialRegister () 23 09:02:23.216 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::SDnsQuery () target=tel.t-online.de 23 09:02:23.217 004f2c Info FrameWork 00000000 00000000 ::GetMyIpAddressForRemoteSide () My best interface IP address to contact remote side: 192.168.0.25 23 09:02:23.223 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupNAPTR () tel.t-online.de 23 09:02:23.223 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupSRV () _sips._tcp.tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupSRV () _sips._udp.tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupSRV () _sip._tcp.tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupSRV () _sip._udp.tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupARecords () tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupARecords () sip.tel.t-online.de 23 09:02:23.224 001f50 Inf3 DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Wait for DNS result, resolving: tel.t-online.de 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::WaitForResult () 23 09:02:23.224 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::WaitForResult () States=0/0/0 timeToWait=5.000 timeDiff=0.000 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () status=0 23 09:02:23.326 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: ims.voip.t-ipnet.de 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupNAPTR () ims.voip.t-ipnet.de 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () status=4 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SrvCount= 3 status=4 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:02:23.326 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () No SRV records for tel.t-online.de 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () status=4 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SrvCount= 2 status=4 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () No SRV records for tel.t-online.de 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:02:23.327 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () status=0 23 09:02:23.328 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SrvCount= 1 status=0 23 09:02:23.328 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () Adding SRV record (no NAPTR): Key=_sip._udp.tel.t-online.de, Transp=1, Prio=1, Wght=5, Port=5060, Target=ims002.voip.t-ipnet.de 23 09:02:23.328 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () Adding SRV record (no NAPTR): Key=_sip._udp.tel.t-online.de, Transp=1, Prio=0, Wght=5, Port=5060, Target=ims001.voip.t-ipnet.de 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresHostCallback () Received A result for tel.t-online.de 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processHost () ACount= 1 status=0 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processHost () DNS A lookup canonical name: b-epp-001.isp.t-ipnet.de 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processHost () Adding 217.0.23.100 to result set 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresSRVCallback () Received SRV result for tel.t-online.de 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () status=4 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SrvCount= 0 status=4 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processSRV () SRV lookup failed: Domain name not found 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresHostCallback () Received A result for tel.t-online.de 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processHost () ACount= 0 status=4 23 09:02:23.329 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processHost () Failed async A query: Domain name not found 23 09:02:23.406 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:02:23.407 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () status=0 23 09:02:23.407 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:02:23.407 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: ims001.voip.t-ipnet.de 23 09:02:23.407 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupNAPTR () ims001.voip.t-ipnet.de 23 09:02:23.487 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:02:23.488 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () status=0 23 09:02:23.488 001f50 Info DnsQuery 00000000 00000000 SDnsQuery::parseNAPTR () Failed parse of RR 23 09:02:23.488 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () NAPTR query got CNAME, re-running on true name: b-epp-001.isp.t-ipnet.de 23 09:02:23.488 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::lookupNAPTR () b-epp-001.isp.t-ipnet.de 23 09:02:23.540 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::aresNAPTRCallback () Received NAPTR result for tel.t-online.de 23 09:02:23.540 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () status=0 23 09:02:23.540 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::processNAPTR () Adding NAPTR record: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= replace=_sip._udp.tel.t-online.de 23 09:02:23.541 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::WaitForResult () leaving while loop, State=1/1/2 timeToWait=5.000 timeDiff=0.312 23 09:02:23.541 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Naptr: Key=b-epp-001.isp.t-ipnet.de, Order=0, Pref=0, Flags=s, Service=SIP+D2U, Regex=, Repl=_sip._udp.tel.t-online.de 23 09:02:23.541 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Found matchin SRV Record: Key=_sip._udp.tel.t-online.de, Transp=1, Prio=0, Wght=5, Port=5060, Target=ims001.voip.t-ipnet.de 23 09:02:23.541 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::SDnsQuery () target=ims001.voip.t-ipnet.de 23 09:02:23.550 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::lookupARecords () ims001.voip.t-ipnet.de 23 09:02:23.550 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::lookupARecords () sip.ims001.voip.t-ipnet.de 23 09:02:23.550 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::WaitForResult () 23 09:02:23.550 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::WaitForResult () States=2/2/0 timeToWait=5.000 timeDiff=0.000 23 09:02:23.607 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::aresHostCallback () Received A result for ims001.voip.t-ipnet.de 23 09:02:23.663 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::processHost () ACount= 1 status=0 23 09:02:23.663 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::processHost () DNS A lookup canonical name: b-epp-001.isp.t-ipnet.de 23 09:02:23.663 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::processHost () Adding 217.0.23.100 to result set 23 09:02:23.663 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::aresHostCallback () Received A result for ims001.voip.t-ipnet.de 23 09:02:23.664 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::processHost () ACount= 0 status=4 23 09:02:23.664 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::processHost () Failed async A query: Domain name not found 23 09:02:23.664 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::WaitForResult () leaving while loop, State=2/2/2 timeToWait=5.000 timeDiff=0.109 23 09:02:23.664 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Resolved SRV Record via A Record: 217.0.23.100 23 09:02:23.664 001f50 Info DnsQuery 0129BB70 00000000 SDnsQuery::~SDnsQuery () 23 09:02:23.664 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Lookup best interface for target address: 217.0.23.100 23 09:02:23.666 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::GetPeerAndOwnAddress Best interface:Intel(R) Ethernet Connection I217-LM, 192.168.0.25 for target: tel.t-online.de, 217.0.23.100 23 09:02:23.666 001f50 Info SwSIPReg 0549C4E8 00000000 SwSIPActReg::ActionSendInitialRegister () refresh stun with ip: 217.0.23.100 resolved via NAPTR/SRV/A 23 09:02:23.666 001f50 Info DnsQuery 05371B20 00000000 SDnsQuery::~SDnsQuery () 23 09:02:23.666 001f50 Info STUN 012B40D0 00000000 SSTUNSocket::AddDestForRefresh () 217.0.23.100:5060 23 09:02:23.667 001f50 Info SwSIPReg 0549C4E8 00000000 SwSIPActReg::MakeRegisterRequest () use SIPConnect v1.1: no 23 09:02:23.667 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (tel.t-online.de) Converted to string: tel.t-online.de 23 09:02:23.667 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (+49123456789) Converted to string: +49123456789 23 09:02:23.667 001f50 Inf3 SwSIP 01292B30 00000000 SwSIPEndp::ConvWideToEscStr (tel.t-online.de) Converted to string: tel.t-online.de 23 09:02:23.667 001f50 Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::MakeRegisterRequest () put STUN address/port to Via-header:Ip:217.1.121.16:45421 23 09:02:23.667 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByAorOrUri () Added registration '+49123456789@tel.t-online.de' to list of registrations! 23 09:02:23.667 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddActRegByAorOrUri () Added registration '+49123456789@217.1.121.16:45421' to list of registrations! 23 09:02:23.667 001f50 Info SwSIP 01292B30 00000000 SwSIPEndp::AddRecogUri () added +49123456789 - +49123456789 @ --> 0129C0F8 23 09:02:23.667 001f50 Info SwSIPReg 0549C4E8 00000000 SwSIPActReg::ActionSendInitialRegister () SEND SIP REGISTER 23 09:02:23.668 001f50 Inf2 SIP 01292B70 00000000 SipStack.cxx:333 () SEND: SipReq: REGISTER tel.t-online.de tid=ff10f30927798349 cseq=REGISTER contact=+49123456789@217.1.121.16:45421 / 1279459196 from(tu) 23 09:02:23.668 001f50 Info SwSIPReg 0549C4E8 00000000 SFsm::OnProcessEvent () Unregistered evtRegister Result: 0 NewSt: UnregisteredRegisterSent 23 09:02:23.668 001f50 Inf3 SwSIPReg 0549C4E8 00000000 SwSIPActReg::OnThreadStopped () 23 09:02:23.668 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:391 () No matching transaction for SipReq: REGISTER tel.t-online.de tid=ff10f30927798349 cseq=REGISTER contact=+49123456789@217.1.121.16:45421 / 1279459196 from(tu) 23 09:02:23.668 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:58 () Creating new TransactionState: tid=ff10f30927798349 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:02:23.668 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:663 () TransactionState::processClientNonInvite: SipReq: REGISTER tel.t-online.de tid=ff10f30927798349 cseq=REGISTER contact=+49123456789@217.1.121.16:45421 / 1279459196 from(tu) 23 09:02:23.668 001020 Inf2 SIP 01292B70 00000000 TimerQueue.cxx:85 () Adding timer: Timer F tid=ff10f30927798349 ms=32000 23 09:02:23.668 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:2043 () sendToWire with no dns result: tid=ff10f30927798349 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:02:23.668 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:415 () Looking up dns entries for sip:tel.t-online.de 23 09:02:23.669 001020 Inf2 SIP 01292B70 00000000 DnsResult.cxx:207 () DnsResult::lookup sip:tel.t-online.de 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:tel.t-online.de NAPTR 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:421 () tel.t-online.de mapped to CNAME b-epp-001.isp.t-ipnet.de 23 09:02:23.669 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:68 () b-epp-001.isp.t-ipnet.de (NAPTR)--> o=0 p=0 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1293 () Received NAPTR result for: sip: target=tel.t-online.de 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1294 () DnsResult::onDnsResult() 0 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1199 () Adding NAPTR record: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= -> replacement=_sip._udp.tel.t-online.de 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:1206 () Picked preferred: key=b-epp-001.isp.t-ipnet.de order=0 pref=0 flags=s service=SIP+D2U regex= -> replacement=_sip._udp.tel.t-online.de 23 09:02:23.669 001020 Info SIP 01292B70 00000000 DnsResult.cxx:1227 () Doing SRV lookup of _sip._udp.tel.t-online.de 23 09:02:23.669 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:_sip._udp.tel.t-online.de SRV 23 09:02:23.670 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:62 () _sip._udp.tel.t-online.de (SRV) --> p=1 w=5 ims002.voip.t-ipnet.de:5060_sip._udp.tel.t-online.de (SRV) --> p=0 w=5 ims001.voip.t-ipnet.de:5060 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:982 () Received SRV result for: tel.t-online.de 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:985 () DnsResult::onDnsResult() 0 status=0 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:503 () Priming [key=_sip._udp.tel.t-online.de t=UDP p=0 w=5 port=5060 target=ims001.voip.t-ipnet.de, key=_sip._udp.tel.t-online.de t=UDP p=1 w=5 port=5060 target=ims002.voip.t-ipnet.de] 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:621 () cumulative weight = 5 selected=2 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:656 () SRV: [key=_sip._udp.tel.t-online.de t=UDP p=1 w=5 port=5060 target=ims002.voip.t-ipnet.de] 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:511 () Primed with SRV=key=_sip._udp.tel.t-online.de t=UDP p=0 w=5 port=5060 target=ims001.voip.t-ipnet.de 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:515 () No A or AAAA record for ims001.voip.t-ipnet.de in additional records 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:395 () DNS query of:ims001.voip.t-ipnet.de A 23 09:02:23.670 001020 Inf3 SIP 01292B70 00000000 DnsStub.cxx:421 () ims001.voip.t-ipnet.de mapped to CNAME b-epp-001.isp.t-ipnet.de 23 09:02:23.670 001020 Inf2 SIP 01292B70 00000000 DnsStub.cxx:44 () b-epp-001.isp.t-ipnet.de(A)--> 217.0.23.100 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:772 () Received dns result for: tel.t-online.de 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:773 () DnsResult::onDnsResult() 0 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:795 () Adding [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] to result set 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:1870 () tid=ff10f30927798349 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] got DNS result: tel.t-online.de --> [[ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ]] 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 DnsResult.cxx:187 () Returning next dns entry: [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] 23 09:02:23.671 001020 Inf3 SIP 01292B70 00000000 TransactionState.cxx:1911 () Unreliable transport: tid=ff10f30927798349 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] 23 09:02:23.671 001020 Inf2 SIP 01292B70 00000000 TimerQueue.cxx:85 () Adding timer: Timer E1 tid=ff10f30927798349 ms=500 23 09:02:23.671 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:522 () hint provided by app: SIP/2.0/ 217.1.121.16:45421;branch=z9hG4bK-d8754z-ff10f30927798349-1---d8754z-;rport 23 09:02:23.671 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1207 () findTransportBySource([ V4 217.1.121.16:45421 UDP target domain=unspecified mFlowKey=0 ]) 23 09:02:23.671 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1261 () findTransport (any interface) => Transport: [ V4 0.0.0.0:65002 UDP target domain=unspecified mFlowKey=0 ] 23 09:02:23.672 001020 Inf2 SIP 01292B70 00000000 TransportSelector.cxx:1039 () Transmitting to [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] tlsDomain= via [ V4 217.1.121.16:45421 UDP target domain=unspecified mFlowKey=0 ] ~ ~REGISTER sip:tel.t-online.de SIP/2.0 ~Via: SIP/2.0/UDP 217.1.121.16:45421;branch=z9hG4bK-d8754z-ff10f30927798349-1---d8754z-;rport ~Max-Forwards: 70 ~Contact: <sip:+49123456789@217.1.121.16:45421> ~To: <sip:+49123456789@tel.t-online.de> ~From: <sip:+49123456789@tel.t-online.de>;tag=505dfb79 ~Call-ID: OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk. ~CSeq: 1279459196 REGISTER ~Expires: 480 ~Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE ~User-Agent: Swyx LinkMgr/10.30.0.81 ~Content-Length: 0 ~ ~ 23 09:02:23.672 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:213 () Adding message to tx buffer to: [ V4 217.0.23.100:5060 UDP target domain=tel.t-online.de mFlowKey=0 ] 23 09:02:23.835 001020 Inf3 STUN 012B40D0 00000000 SSTUNSocket::ReadFrom () received SIP data at STUN socket, len=567 23 09:02:23.835 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:287 () incoming from: [ V4 217.0.23.100:5060 UDP target domain=unspecified mFlowKey=0 ] 23 09:02:23.835 001020 Inf2 SIP 01292B70 00000000 Transport.cxx:288 () ~ ~SIP/2.0 401 Unauthorized ~Via: SIP/2.0/UDP 217.1.121.16:45421;received=217.1.121.16;rport=49253;branch=z9hG4bK-d8754z-ff10f30927798349-1---d8754z- ~Service-Route: <sip:217.0.23.100:5060;transport=udp;lr> ~To: <sip:+49123456789@tel.t-online.de>;tag=h7g4Esbg_b0cdde720790f4e060c6d6c1cf2c104e ~From: <sip:+49123456789@tel.t-online.de>;tag=505dfb79 ~Call-ID: OWZkZmE3N2IxMzI3NWQwZDEzNWRlNTA1MDFlMmZkNTk. ~CSeq: 1279459196 REGISTER ~WWW-Authenticate: Digest realm="tel.t-online.de",nonce="CEEE990E96B885580000000073659C41",algorithm=MD5,qop="auth" ~Content-Length: 0 ~ ~ Könnt ihr mir einen Tipp in die richtige Richtung geben? Vielen Dank! Link to comment Share on other sites More sharing options...
redline Posted January 25, 2017 Author #2 Share Posted January 25, 2017 Die Lösung ist banal wie frustrierend. Bei den Telekom Voice/Data Anschlüssen ist es notwendig, dass die DSL Einwahl über den selben Anschluss erfolgt. Technisch bedingt sendet die Telekom die Antwortpakete nicht an den Anfrageranschluss, sondern an den eigentlichen Anschluss für welchen die Zugangsdaten gelten. Da der SwyxServer aber ein anderes Gateway nutzt, erhält er diese Informationen nie. So ein Mist. :-) Trotzdem Danke! Link to comment Share on other sites More sharing options...
Recommended Posts
Archived
This topic is now archived and is closed to further replies.