INFO | 20220629-081524.416 | RESIP:DUM | 518212005040 | ServerInviteSession.cxx:527 | InviteSession::Connected: end DEBUG | 20220629-081524.417 | RESIP:DUM | 518212005040 | Dialog.cxx:1079 | Dialog::makeRequest: BYE sip:asterisk@213.37.134.165:5060;transport=TCP SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---a0d2284ef1330822;rport Max-Forwards: 70 Contact: To: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 From: ;tag=537a8434 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 2 BYE Content-Length: 0 INFO | 20220629-081524.417 | RESIP:DUM | 518212005040 | InviteSession.cxx:3169 | ;tag=537a8434 Sending BYE DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call_manager.cpp:1052 | AppDialogSet 0xb400007968af4ff0 id=12 userId=9349379 callId=9353035 aor= DEBUG | 20220629-081524.417 | RESIP:DUM | 518212005040 | DialogUsageManager.cxx:1011 | SEND: BYE sip:asterisk@213.37.134.165:5060;transport=TCP SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---0ed7b90abf3bb0a0;rport Max-Forwards: 70 Contact: To: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 From: ;tag=537a8434 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 2 BYE User-Agent: Zoiper v2.10.17.3-mod Content-Length: 0 DEBUG | 20220629-081524.417 | RESIP:DUM | 518212005040 | DialogId.cxx:50 | DialogId::DialogId: 02a075b2-7e44-47a2-8702-485fadde793e-537a8434-14a55af9-bab6-48c9-9e8d-78c0e1707cf6 DEBUG | 20220629-081524.417 | RESIP:DUM | 518212005040 | DialogUsageManager.cxx:1172 | Send: SipReq: BYE asterisk@213.37.134.165:5060 tid=0ed7b90abf3bb0a0 cseq=2 BYE contact=6002@192.168.0.15:33521 / 2 from(tu) DEBUG | 20220629-081524.417 | RESIP | 518212005040 | SipStack.cxx:857 | SEND: SipReq: BYE asterisk@213.37.134.165:5060 tid=0ed7b90abf3bb0a0 cseq=2 BYE contact=6002@192.168.0.15:33521 / 2 from(tu) INFO | 20220629-081524.417 | RESIP:DUM | 518212005040 | InviteSession.cxx:2804 | Transition InviteSession::Connected -> InviteSession::Terminated DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call_manager.cpp:2986 | onTerminated for 14 reason= 3 DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call_manager.cpp:2989 | msg= SipReq: BYE asterisk@213.37.134.165:5060 tid=0ed7b90abf3bb0a0 cseq=2 BYE contact=6002@192.168.0.15:33521 / 2 from(tu) DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call_manager.cpp:2990 | app dlg= AppDialog 0xb400007948baf710 id=13 userId=9349379 callId=-1 DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call_manager.cpp:1052 | AppDialogSet 0xb400007968af4ff0 id=12 userId=9349379 callId=9353035 aor= DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | call.cpp:102 | Call 0x8eb74b state 8 -> 22 DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | sip_call.cpp:2000 | DestroyRtpStream: 0xb400007ab8b36380 DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | rtp_stream.cpp:59 | ~RtpStream 0xb400007bbcdad1a0 DEBUG | 20220629-081524.417 | WRAPPER | 518212005040 | rtp_stream.cpp:325 | Destroy 0xb400007bbcdad1a0 DEBUG | 20220629-081524.418 | WRAPPER | 518212005040 | neteq_stream.cpp:140 | Destroy 0xb400007bbcdad1a0 DEBUG | 20220629-081524.418 | WRAPPER | 518212005040 | neteq_stream.cpp:196 | Stop 0xb400007bbcdad1a0 DEBUG | 20220629-081524.418 | WRAPPER | 518212005040 | neteq_stream.cpp:827 | InternalUnregisterStream 0xb400007bbcdad1a0: mixer= 0xb400007938b1adf0, source= 0xb400007948bae650, sink= 0xb400007968af1630 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | neteq_stream.cpp:835 | InternalUnregisterStream: mixer= 0xb400007938b1adf0, source= 0xb400007948bae650, sink= 0xb400007968af1630 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_builder.cpp:413 | Unregister source= 0xb400007948bae670 sink= 0xb400007968af1670 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | multirate_source_mixer.cpp:187 | RemoveSource 0xb400007998ce3f80: source= 0xb400007948bae670 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | multirate_source_mixer.cpp:231 | ~SourceWrapper 0xb400007968afcb50 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_builder.cpp:816 | Source 0xb400007948bae670 removed from mixer 0xb400007998ce3f80 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | multirate_sink_tee.cpp:160 | RemoveSink 0xb400007988b84bc0: sink= 0xb400007968af1670 ctl= 0xb400007a08ad0c78 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_builder.cpp:825 | Sink 0xb400007968af1670 removed from tee 0xb400007988b84bc0 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_builder.cpp:840 | Removed last stream, shutting down graph DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_builder.cpp:870 | InternalReset 0xb400007a48acf538 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_graph.cpp:137 | Stop 0xb4000079d8af85f0 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | audio_graph.cpp:184 | Shutting down graph DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | base_endpoint.cpp:95 | Stop 0xb400007a08ae4bf0: graph= 0xb4000079d8af8730 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | pa_endpoint.cpp:80 | InternalStop 0xb400007a08ae4bf0 DEBUG | 20220629-081524.418 | WRAPPER | 517464284336 | pa_endpoint.cpp:463 | PaClose 0xb400007a08ae4bf0 DEBUG | 20220629-081524.422 | WRAPPER | 518212005040 | neteq_stream.cpp:222 | Stop: Thread joined 0xb400007bbcdad1a0 DEBUG | 20220629-081524.422 | WRAPPER | 518212005040 | neteq_source.cpp:101 | Destroy: 0xb4000079e8c65550 DEBUG | 20220629-081524.422 | WRAPPER | 518212005040 | neteq_source.cpp:390 | State: 0xb4000079e8c65550 Created -> Initial DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | rtp_port_manager.cpp:142 | ReleaseSocket: releasing port: 46341; socket: 147 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_stream.cpp:67 | ~NetEqStream 0xb400007bbcdad1a0 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_stream.cpp:140 | Destroy 0xb400007bbcdad1a0 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_stream.cpp:196 | Stop 0xb400007bbcdad1a0 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_stream.cpp:201 | Stop: Not started DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_source.cpp:101 | Destroy: 0xb4000079e8c65550 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | neteq_source.cpp:106 | Destroy: Invalid state Initial DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | sip_call.cpp:2010 | DestroyVideoStream: 0xb400007ab8b36380 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | appl_event_queue.cpp:167 | Posting event DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | appl_event_queue.cpp:45 | Notify: event= onCallHangup(0x8eb74b, 1552) DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | appl_event_queue.cpp:145 | SetCallCreateSent, cur= 1 DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | sip_call.cpp:76 | ~SipCall: 0xb400007ab8b36380, id= 0x8eb74b DEBUG | 20220629-081524.434 | WRAPPER | 518212005040 | sip_call_manager.cpp:1004 | GetCallById: Call 0x8eb74b does not exist DEBUG | 20220629-081524.435 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:50 | Adding timer: Timer F tid=0ed7b90abf3bb0a0 ms=32000 DEBUG | 20220629-081524.435 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:611 | Looking up dns entries for sip:asterisk@213.37.134.165:5060;transport=TCP DEBUG | 20220629-081524.435 | RESIP:DNS | 517446466736 | DnsResult.cxx:262 | DnsResult::lookup sip:asterisk@213.37.134.165:5060;transport=TCP DEBUG | 20220629-081524.435 | RESIP:DNS | 517446466736 | DnsResult.cxx:334 | Found immediate result: [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 ] DEBUG | 20220629-081524.435 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1054 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081524.436 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 ] -> [ V4 192.168.0.15:0 TCP targetDomain=213.37.134.165 ] sent-by= sent-port=0 DEBUG | 20220629-081524.437 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=213.37.134.165 ] BYE sip:asterisk@213.37.134.165:5060;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 192.168.0.15:48017;branch=z9hG4bK-524287-1---0ed7b90abf3bb0a0;rport Max-Forwards: 70 Contact: To: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 From: ;tag=537a8434 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 2 BYE User-Agent: Zoiper v2.10.17.3-mod Content-Length: 0 sigcomp id= DEBUG | 20220629-081524.437 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:344 | Processing write for [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] DEBUG | 20220629-081524.437 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:93 | Could not find a connection for [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] DEBUG | 20220629-081524.437 | RESIP:TRANSPORT | 517446466736 | InternalTransport.cxx:121 | Creating fd=147 V4/TCP DEBUG | 20220629-081524.437 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:276 | Opening new connection to [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] DEBUG | 20220629-081524.439 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:70 | ConnectionBase::ConnectionBase, who: [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] 0xb400007a08acdb30 DEBUG | 20220629-081524.439 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:83 | No compression library available: 0xb400007a08acdb30 INFO | 20220629-081524.439 | RESIP:TRANSPORT | 517446466736 | Connection.cxx:127 | Connection::Connection: new connection created to who: [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 flowKey=147 transportKey=2 ], is server = 0 DEBUG | 20220629-081524.439 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:187 | ConnectionManager::addConnection() 147:[ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 flowKey=147 transportKey=2 ], totalConnections=1 DEBUG | 20220629-081524.439 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:18 | Creating TCP connection [ V4 213.37.134.165:5060 TCP targetDomain=213.37.134.165 transportKey=2 ] on 147 DEBUG | 20220629-081524.439 | WRAPPER | 517464284336 | pa_endpoint.cpp:479 | PaClose: m_InStream closed successfully DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | pa_endpoint.cpp:498 | PaClose: m_OutStream closed successfully DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | base_endpoint.cpp:203 | Transition 0xb400007a08ae4bf0: Running -> Stopped DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | audio_graph.cpp:86 | Unregister Thread 0xb400007a08ad0c78 DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | audio_graph.cpp:86 | Unregister Thread 0xb400007a08adcb18 DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | audio_thread.cpp:39 | ~BaseAudioThread: 0xb400007a08ad0a10: PaEndpoint::Sink DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | audio_thread.cpp:39 | ~BaseAudioThread: 0xb400007a08adc8b0: PaEndpoint::Source DEBUG | 20220629-081524.448 | WRAPPER | 517464284336 | audio_graph.cpp:189 | Waiting for the graph control thread DEBUG | 20220629-081524.449 | WRAPPER | 518209875120 | audio_graph.cpp:115 | Graph thread 0xb4000079d8af85f0exiting DEBUG | 20220629-081524.449 | WRAPPER | 517464284336 | audio_graph.cpp:194 | Done DEBUG | 20220629-081524.449 | WRAPPER | 517464284336 | base_endpoint.cpp:162 | Unregister 0xb400007a08ae4bf0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_graph.cpp:95 | Unregister Stream 0xb400007a08ae4e98 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_graph.cpp:86 | Unregister Thread 0xb400007958b35510 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | level_tracker.cpp:38 | ~LevelTracker 0xb400007958b35510 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | appl_event_queue.cpp:45 | Notify: event= [N3scx16CCallAudioLevelsE] DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_graph.cpp:86 | Unregister Thread 0xb4000079c8b9f6b0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | volume_control.cpp:69 | VolumeControl 0xb4000079c8be1170 copying from 0xb4000079c8b9f6b0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | volume_control.cpp:281 | UpdateInput: no input endpoint DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | volume_control.cpp:313 | UpdateOutput: no output endpoint DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_graph.cpp:40 | ~Graph 0xb4000079d8af85f0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | multirate_source_mixer.cpp:39 | ~MultirateSourceMixer 0xb400007998ce3f80 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | voice_processor.cpp:158 | ~VoiceProcessor 0xb4000079b8b2f470 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | volume_control.cpp:34 | ~VolumeControl 0xb4000079c8b9f6b0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_builder.cpp:1685 | Input Audio High Thread total runs: 766 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_builder.cpp:1686 | Input Audio High Thread overrun 25ms: 12 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_builder.cpp:1687 | Input Audio High Thread overrun 50ms: 0 DEBUG | 20220629-081524.450 | WRAPPER | 517464284336 | audio_builder.cpp:1688 | Output Audio High Thread total runs: 767 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | audio_builder.cpp:1689 | Output Audio High Thread overrun 25ms: 5 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | audio_builder.cpp:1690 | Output Audio High Thread overrun 50ms: 0 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | appl_event_queue.cpp:45 | Notify: event= [N3scx26CAudioHighThreadStatisticsE] DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | pa_endpoint.cpp:50 | ~PaEndpoint 0xb400007a08ae4bf0 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | base_endpoint.cpp:32 | ~BaseEndpoint 0xb400007a08ae4bf0: PaEndpoint DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | multirate_sink_tee.cpp:35 | ~MultirateSinkTee 0xb400007988b84bc0 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | audio_builder.cpp:863 | Transition: Direct -> Idle DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | neteq_source.cpp:50 | ~NetEqSource: 0xb4000079e8c65550 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | neteq_source.cpp:101 | Destroy: 0xb4000079e8c65550 DEBUG | 20220629-081524.451 | WRAPPER | 517464284336 | neteq_source.cpp:106 | Destroy: Invalid state Initial DEBUG | 20220629-081532.539 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) DEBUG | 20220629-081532.540 | RESIP:DUM | 517446466736 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ], interval=30s, supportsOutbound=false, refCount=2 DEBUG | 20220629-081532.541 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) ms=30000 DEBUG | 20220629-081532.542 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1054 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081532.546 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] -> [ V4 192.168.0.15:0 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sent-by= sent-port=0 DEBUG | 20220629-081532.546 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sigcomp id= DEBUG | 20220629-081532.546 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:344 | Processing write for [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081532.546 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:65 | Found fd 143 DEBUG | 20220629-081532.547 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:108 | Wrote 4 bytes DEBUG | 20220629-081540.942 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 DumTimeout::WaitForAck: duration=32000 seq=23753 INFO | 20220629-081541.001 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:87 | Stats message WARNING | 20220629-081541.002 | RESIP:STATS | 517446466736 | StatisticsMessage.cxx:98 | RESIP:TRANSACTION TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 1 SERVERTX 1 TIMERS 2 Transaction summary: reqi 5 reqo 11 rspi 8 rspo 7 Details: INVi 2/S2/F0 INVo 0/S0/F0 ACKi 2 ACKo 0 BYEi 1/S1/F0 BYEo 2/S0/F0 CANi 0/S1/F0 CANo 0/S0/F0 MSGi 0/S0/F0 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 0/S0/F0 REGo 9/S5/F3 PUBi 0/S0/F0 PUBo 0/S0/F0 SUBi 0/S0/F0 SUBo 0/S0/F0 NOTi 0/S0/F0 NOTo 0/S0/F0 REFi 0/S0/F0 REFo 0/S0/F0 INFi 0/S0/F0 INFo 0/S0/F0 PRAi 0/S0/F0 PRAo 0/S0/F0 SERi 0/S0/F0 SERo 0/S0/F0 UPDi 0/S0/F0 UPDo 0/S0/F0 Retransmissions: INVx 0 finx 0 nonx 0 BYEx 0 CANx 0 MSGx 0 OPTx 0 REGx 0 PUBx 0 SUBx 0 NOTx 0 REFx 0 INFx 0 PRAx 0 SERx 0 UPDx 0 DEBUG | 20220629-081556.436 | RESIP | 517446466736 | Helper.cxx:375 | Helper::makeResponse(SipReq: BYE asterisk@213.37.134.165:5060 tid=0ed7b90abf3bb0a0 cseq=2 BYE contact=6002@192.168.0.15:33521 / 2 from(tu) code=408 reason= DEBUG | 20220629-081556.438 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 SIP/2.0 408 Request Timeout Via: SIP/2.0/TCP 192.168.0.15:48017;branch=z9hG4bK-524287-1---0ed7b90abf3bb0a0;rport To: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 From: ;tag=537a8434 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 2 BYE Content-Length: 0 DEBUG | 20220629-081556.439 | RESIP:DNS | 517446466736 | DnsResult.cxx:188 | Remove vip 213.37.134.165(1) DEBUG | 20220629-081556.439 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:1627 | Got: SipResp: 408 tid=0ed7b90abf3bb0a0 cseq=2 BYE / 2 from(wire) DEBUG | 20220629-081556.439 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:2177 | DialogUsageManager::processResponse: SipResp: 408 tid=0ed7b90abf3bb0a0 cseq=2 BYE / 2 from(wire) DEBUG | 20220629-081556.440 | RESIP:DUM | 517446466736 | DialogId.cxx:50 | DialogId::DialogId: 02a075b2-7e44-47a2-8702-485fadde793e-537a8434-14a55af9-bab6-48c9-9e8d-78c0e1707cf6 DEBUG | 20220629-081556.440 | RESIP:DUM | 517446466736 | DialogSet.cxx:531 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for SIP/2.0 408 Request Timeout Via: SIP/2.0/TCP 192.168.0.15:48017;branch=z9hG4bK-524287-1---0ed7b90abf3bb0a0;rport To: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 From: ;tag=537a8434 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 2 BYE Content-Length: 0 DEBUG | 20220629-081556.440 | RESIP:DUM | 517446466736 | Dialog.cxx:446 | Dialog::dispatch: SipResp: 408 tid=0ed7b90abf3bb0a0 cseq=2 BYE / 2 from(wire) INFO | 20220629-081556.441 | RESIP:DUM | 517446466736 | InviteSession.cxx:2046 | InviteSession::dispatchTerminated SipResp: 408 tid=0ed7b90abf3bb0a0 cseq=2 BYE / 2 from(wire) DEBUG | 20220629-081556.442 | RESIP:DUM | 517446466736 | InviteSession.cxx:91 | ^^^ InviteSession::~InviteSession 0xb400007aa8b8e720 DEBUG | 20220629-081556.443 | RESIP:DUM | 517446466736 | Dialog.cxx:280 | Dialog::~Dialog() DEBUG | 20220629-081556.443 | WRAPPER | 517446466736 | app_dialogs.cpp:33 | ~AppDialog 0xb400007948baf710 id=13 userId=9349379 callId=-1 DEBUG | 20220629-081556.444 | RESIP:DUM | 517446466736 | KeepAliveManager.cxx:83 | Association removed for keep alive id=4: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ], refCount=1 DEBUG | 20220629-081556.444 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:2529 | Got merged request removal request DEBUG | 20220629-081556.444 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:124 | Adding application timer: ms=32000 DEBUG | 20220629-081556.444 | RESIP:DUM | 517446466736 | DialogSet.cxx:124 | ********** DialogSet::~DialogSet: 02a075b2-7e44-47a2-8702-485fadde793e-537a8434************* DEBUG | 20220629-081556.445 | WRAPPER | 517446466736 | app_dialogs.cpp:79 | ~AppDialogSet 0xb400007968af4ff0 user= 0x8ea903 call= 0x8eb74b DEBUG | 20220629-081557.438 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:136 | In State: NewMessage DEBUG | 20220629-081557.438 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:182 | ConnectionBase::process setting source [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081557.439 | RESIP:TRANSPORT | 517446466736 | Transport.cxx:397 | incoming from: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081557.439 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:437 | ##Connection: CONN_BASE: 0xb400007a08acc870 [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] received: BYE sip:6002@192.168.0.15:33521;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 213.37.134.165:5060;rport=5060;branch=z9hG4bKPje133b703-ec3c-45d8-9a25-31deadb64abc;received=192.168.0.32;alias Max-Forwards: 70 To: ;tag=537a8434 From: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 23754 BYE User-Agent: Asterisk PBX 19.3.0 Reason: Q.850;cause=16 Content-Length: 0 DEBUG | 20220629-081557.440 | RESIP:TRANSPORT | 517446466736 | Connection.cxx:620 | Connection::performReads() read=461 DEBUG | 20220629-081557.440 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:1046 | Creating buffer for CONN_BASE: 0xb400007a08acc870 [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081557.441 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:199 | TuSelector::selectTransactionUser: Checking which TU message belongs to: BYE sip:6002@192.168.0.15:33521;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 213.37.134.165:5060;rport=5060;branch=z9hG4bKPje133b703-ec3c-45d8-9a25-31deadb64abc;received=192.168.0.32;alias Max-Forwards: 70 To: ;tag=537a8434 From: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 23754 BYE User-Agent: Asterisk PBX 19.3.0 Reason: Q.850;cause=16 Content-Length: 0 DEBUG | 20220629-081557.441 | RESIP:TRANSACTION | 517446466736 | TransactionUser.cxx:86 | TransactionUser::isForMe: TU=DialogUsageManager, Checking rule... : SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) DEBUG | 20220629-081557.441 | RESIP:TRANSACTION | 517446466736 | TransactionUser.cxx:89 | TransactionUser::isForMe: TU=DialogUsageManager, Match! : SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) DEBUG | 20220629-081557.442 | RESIP | 517446466736 | Helper.cxx:375 | Helper::makeResponse(SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) code=100 reason= DEBUG | 20220629-081557.442 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:50 | Adding timer: Timer Trying tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc ms=3500 DEBUG | 20220629-081557.442 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 BYE sip:6002@192.168.0.15:33521;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 213.37.134.165:5060;rport=5060;branch=z9hG4bKPje133b703-ec3c-45d8-9a25-31deadb64abc;received=192.168.0.32;alias Max-Forwards: 70 To: ;tag=537a8434 From: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 23754 BYE User-Agent: Asterisk PBX 19.3.0 Reason: Q.850;cause=16 Content-Length: 0 DEBUG | 20220629-081557.443 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:1627 | Got: SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) DEBUG | 20220629-081557.443 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:1985 | DialogUsageManager::processRequest: SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) DEBUG | 20220629-081557.443 | RESIP | 517446466736 | Helper.cxx:375 | Helper::makeResponse(SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) code=481 reason= INFO | 20220629-081557.443 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:2035 | Rejected request (which was in a dialog) SipReq: BYE 6002@192.168.0.15:33521 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(wire) DEBUG | 20220629-081557.444 | RESIP | 517446466736 | SipStack.cxx:840 | SEND: SipResp: 481 tid=Pje133b703-ec3c-45d8-9a25-31deadb64abc cseq=23754 BYE / 23754 from(tu) DEBUG | 20220629-081557.444 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1191 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081557.449 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] -> [ V4 192.168.0.15:0 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sent-by=213.37.134.165 sent-port=5060 DEBUG | 20220629-081557.449 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] SIP/2.0 481 Call/Transaction Does Not Exist Via: SIP/2.0/TCP 213.37.134.165:5060;rport=5060;branch=z9hG4bKPje133b703-ec3c-45d8-9a25-31deadb64abc;received=192.168.0.32;alias To: ;tag=537a8434 From: ;tag=14a55af9-bab6-48c9-9e8d-78c0e1707cf6 Call-ID: 02a075b2-7e44-47a2-8702-485fadde793e CSeq: 23754 BYE Accept-Language: en Content-Length: 0 sigcomp id= DEBUG | 20220629-081557.450 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:344 | Processing write for [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081557.450 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:65 | Found fd 143 DEBUG | 20220629-081557.451 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:108 | Wrote 423 bytes DEBUG | 20220629-081602.542 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) DEBUG | 20220629-081602.543 | RESIP:DUM | 517446466736 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ], interval=30s, supportsOutbound=false, refCount=1 DEBUG | 20220629-081602.544 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) ms=30000 DEBUG | 20220629-081602.545 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1054 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081602.549 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] -> [ V4 192.168.0.15:0 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sent-by= sent-port=0 DEBUG | 20220629-081602.549 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sigcomp id= DEBUG | 20220629-081602.549 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:344 | Processing write for [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081602.549 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:65 | Found fd 143 DEBUG | 20220629-081602.550 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:108 | Wrote 4 bytes DEBUG | 20220629-081606.519 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 DumTimeout::Retransmit1xx: duration=60 seq=1 DEBUG | 20220629-081626.146 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:136 | In State: NewMessage DEBUG | 20220629-081626.146 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:146 | Got incoming double-CRLF keepalive (aka ping). DEBUG | 20220629-081626.146 | RESIP:TRANSPORT | 517446466736 | Connection.cxx:651 | Sending response CRLF (aka pong). DEBUG | 20220629-081626.146 | RESIP:TRANSPORT | 517446466736 | Connection.cxx:620 | Connection::performReads() read=4 DEBUG | 20220629-081626.147 | RESIP:TRANSPORT | 517446466736 | ConnectionBase.cxx:1046 | Creating buffer for CONN_BASE: 0xb400007a08acc870 [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081626.148 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:108 | Wrote 2 bytes DEBUG | 20220629-081628.445 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 DEBUG | 20220629-081628.445 | RESIP:DUM | 517446466736 | DialogUsageManager.cxx:2538 | Merged request removed DEBUG | 20220629-081632.546 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) DEBUG | 20220629-081632.547 | RESIP:DUM | 517446466736 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ], interval=30s, supportsOutbound=false, refCount=1 DEBUG | 20220629-081632.548 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) ms=30000 DEBUG | 20220629-081632.549 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1054 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081632.553 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] -> [ V4 192.168.0.15:0 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sent-by= sent-port=0 DEBUG | 20220629-081632.553 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sigcomp id= DEBUG | 20220629-081632.553 | RESIP:TRANSPORT | 517446466736 | TcpBaseTransport.cxx:344 | Processing write for [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] DEBUG | 20220629-081632.553 | RESIP:TRANSPORT | 517446466736 | ConnectionManager.cxx:65 | Found fd 143 DEBUG | 20220629-081632.559 | RESIP:TRANSPORT | 517446466736 | TcpConnection.cxx:108 | Wrote 4 bytes INFO | 20220629-081641.029 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:87 | Stats message WARNING | 20220629-081641.029 | RESIP:STATS | 517446466736 | StatisticsMessage.cxx:98 | RESIP:TRANSACTION TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 0 SERVERTX 0 TIMERS 0 Transaction summary: reqi 6 reqo 11 rspi 8 rspo 8 Details: INVi 2/S2/F0 INVo 0/S0/F0 ACKi 2 ACKo 0 BYEi 2/S1/F1 BYEo 2/S0/F0 CANi 0/S1/F1 CANo 0/S0/F0 MSGi 0/S0/F0 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 0/S0/F0 REGo 9/S5/F3 PUBi 0/S0/F0 PUBo 0/S0/F0 SUBi 0/S0/F0 SUBo 0/S0/F0 NOTi 0/S0/F0 NOTo 0/S0/F0 REFi 0/S0/F0 REFo 0/S0/F0 INFi 0/S0/F0 INFo 0/S0/F0 PRAi 0/S0/F0 PRAo 0/S0/F0 SERi 0/S0/F0 SERo 0/S0/F0 UPDi 0/S0/F0 UPDo 0/S0/F0 Retransmissions: INVx 0 finx 0 nonx 0 BYEx 0 CANx 0 MSGx 0 OPTx 0 REGx 0 PUBx 0 SUBx 0 NOTx 0 REFx 0 INFx 0 PRAx 0 SERx 0 UPDx 0 DEBUG | 20220629-081702.550 | RESIP:TRANSACTION | 517446466736 | TuSelector.cxx:73 | Send to TU: DialogUsageManager size=0 KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) DEBUG | 20220629-081702.551 | RESIP:DUM | 517446466736 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ], interval=30s, supportsOutbound=false, refCount=1 DEBUG | 20220629-081702.552 | RESIP:TRANSACTION | 517446466736 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout[ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ](4) ms=30000 DEBUG | 20220629-081702.552 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1054 | Found transport: [ V4 0.0.0.0:48017 TCP transportKey=2 ] DEBUG | 20220629-081702.557 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:990 | Looked up source for destination: [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] -> [ V4 192.168.0.15:0 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] sent-by= sent-port=0 DEBUG | 20220629-081702.557 | RESIP:TRANSPORT | 517446466736 | TransportSelector.cxx:1407 | Transmitting to [ V4 192.168.0.32:5060 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ] tlsDomain= via [ V4 192.168.0.15:48017 TCP targetDomain=192.168.0.32 flowKey=143 transportKey=2 ]