Open papagenox opened 1 year ago
版本12.7
每次voicemail时间一长就导致死机。
内存1G，是少了点，但是，出问题时似乎没有记录什么原因。 后来在稍大一点的机器上试了，没有问题。
很奇怪，portsip是docker安装，为什么会把主机弄宕机了？
root@asip:/var/lib/portsip/log# tail callmanager.log -f DEBUG | 20221026-162226.270 | callmanager | RESIP | 0x7f1c0c1cf700 | SipStack.cxx:842 | SEND: SipResp: 200 tid=c26c945c9f8ad676 cseq=5 REGISTER contact=6300@222.129.52.131:25742 / 5 from(tu) DEBUG | 20221026-162226.270 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/PresenceSubscriptionHandler.cxx:473 | PresenceSubscriptionHandler::checkRegistrationStateChanged: registration state unchanged for aor=sip:6300@example.com DEBUG | 20221026-162226.270 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2529 | Got merged request removal request DEBUG | 20221026-162226.270 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: ms=32000 DEBUG | 20221026-162226.271 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
SIP/2.0 200 OK Via: SIP/2.0/TLS 192.168.3.10:5960;branch=z9hG4bK-524287-1---c26c945c9f8ad676;rport=25742;received=222.129.52.131 Require: outbound Contact: sip:6300@222.129.52.131:25742;transport=tls;+sip.instance="";reg-id=1;expires=300;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" To: "Papa"sip:6300@example.com;tag=748ae517 From: "Papa"sip:6300@example.com;tag=f3a69960 Call-ID: NjViQH2JpUyufHT693lbmw.. CSeq: 5 REGISTER Date: Wed, 26 Oct 2022 08:22:26 GMT User-Agent: PortSIP UC - Call Manager 12.7.2.11 x-p-push: enabled=true;id=+TGE1aKu78Jif4qvZXdfv+KlsbSju3gA4lrBQ08T8mqC+bYCA6YCM39QoImlADU4 Content-Length: 0
sigcomp id= DEBUG | 20221026-162226.271 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162226.270 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:124 | ** DialogSet::~DialogSet: NjViQH2JpUyufHT693lbmw..-072a2730***** DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
KeepAliveTimeout V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 DEBUG | 20221026-162226.325 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=2: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ], interval=90s, supportsOutbound=false, refCount=2 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
sigcomp id= DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ms=90000 DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:473 | Received response CRLF (aka pong). DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=2 DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.733 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1387 | keepalive pong received from [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.733 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:154 | Received pong response for keep alive id=2: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162227.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::CanDiscardAck: duration=32000 seq=2 DEBUG | 20221026-162227.769 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::CanDiscardAck: duration=32000 seq=2 DEBUG | 20221026-162229.782 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleCall: duration=180 seq=2 DEBUG | 20221026-162235.534 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleReInvite: duration=40 seq=2 DEBUG | 20221026-162235.534 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=1
DumTimeout::StaleReInvite: duration=40 seq=2 INFO | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:532 | Exception on socket 100 code: 110; closing connection ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:164 | Unexpected error in SSL_shutdown ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:47 | error:140E0114:SSL routines:SSL_shutdown:uninitialized DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:48 | Error code = 336462100 file=../ssl/ssl_lib.c line=2079 ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:51 | Got TLS SSL_shutdown error=1 ret=-1 DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:217 | ConnectionManager::removeConnection() DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:115 | ConnectionBase::~ConnectionBase 0x7f1c4c02d6f0 INFO | 20221026-162235.806 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:102 | Sending ConnectionTerminated [ V4 222.129.52.131:25794 TLS targetDomain=222.129.52.131 flowKey=100 transportKey=1 ] to TUs DEBUG | 20221026-162235.806 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1479 | connection terminated message DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162258.272 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DEBUG | 20221026-162258.273 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2538 | Merged request removed INFO | 20221026-162308.103 | callmanager | PortPBX | 0x7f1c101d7700 | ../../portpbx/CDRManager.cxx:165 | === Processed CDR, total = 13, CDR Fifo size = 0 DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162325.416 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleCall: duration=180 seq=2 DEBUG | 20221026-162325.422 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
KeepAliveTimeout V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 DEBUG | 20221026-162325.423 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ], interval=60s, supportsOutbound=false, refCount=1 DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
sigcomp id= DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ms=60000 DEBUG | 20221026-162325.532 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::SessionRefresh: duration=90 seq=2 INFO | 20221026-162325.532 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::Connected -> InviteSession::SentReinvite DEBUG | 20221026-162325.532 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---cfdafa52c5e63a7f;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
v=0 o=- 1666772365 1 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 45004 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:k6h6iqruNLenXQjEXR+MeN80ELDOe0KYOUiPq83D a=encryption:optional a=sendrecv
INFO | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2540 | InviteSession::SentReinvite: startStaleReInviteTimer DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::StaleReInvite: duration=40 seq=4 ms=40000 INFO | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2596 | sessionRefresh: Sending SipReq: INVITE 999@10.0.0.13:8810 tid=cfdafa52c5e63a7f cseq=3 INVITE contact=6300 / 3 from(tu) DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---f365b00e57338876;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer B tid=f365b00e57338876 ms=32000 DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer A tid=f365b00e57338876 ms=500 DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport Max-Forwards: 70 Contact: sip:6300@10.0.0.13:24611;ob To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
v=0 o=- 1666772365 1 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 45004 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:k6h6iqruNLenXQjEXR+MeN80ELDOe0KYOUiPq83D a=encryption:optional a=sendrecv sigcomp id= DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: INVITE 999@10.0.0.13:8810 tid=f365b00e57338876 cseq=3 INVITE contact=6300 / 3 from(tu) DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Transport.cxx:401 | incoming from: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport=24611 Require: timer Contact: sip:999@10.0.0.13:8810;+sip.instance="" To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH Content-Type: application/sdp Supported: replaces, answermode, eventlist, outbound, path, timer User-Agent: PortSIP UC - voicemail 12.6.0 Allow-Events: hold, talk, conference, dialog Content-Length: 415
v=0 o=- 1666772425 3 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 39000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1627 | Got: SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1641 | DialogUsageManager::process: found SipMessage DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2177 | DialogUsageManager::processResponse:
SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:485 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:459 | Dialog::dispatch: SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | SipMessage.cxx:1067 | SipMessage::getContents: application/sdp DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
INFO | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::SentReinvite -> InviteSession::Connected DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::SessionRefresh: duration=90 seq=3 ms=90000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 0 ACK Content-Length: 0
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::CanDiscardAck: duration=32000 seq=3 ms=32000 INFO | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:3234 | Sending SipReq: ACK 999@10.0.0.13:8810 tid=ffbdbf0a5d49fa01 cseq=3 ACK contact=6300 / 3 from(tu) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 0
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer StaleClient tid=f365b00e57338876 ms=32000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer Stateless tid=ffbdbf0a5d49fa01 ms=32000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: ACK 999@10.0.0.13:8810 tid=ffbdbf0a5d49fa01 cseq=3 ACK contact=6300 / 3 from(tu) DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:63 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634- DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] INFO | 20221026-162325.535 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/DialogInfoSubscriptionHandler.cxx:111 | DialogInfoSubscriptionHandler::onConfirmed, entity=sip:999@example.com, id=urn:uuid:eb7ccf62-c37c-4f4d-7e74-86e819f7490c, localIdentity=sip:6300@example.com, remoteIdentity=sip:999@example.com, callId=QpW70BheA9J8X9LYPWm1_A.., localTag=ab361634, remoteTag=eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: sip:6300@10.0.0.13:24611;ob To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 0
sigcomp id= INFO | 20221026-162325.535 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/B2BSession.cxx:5984 | B2BSession[5] onRemoteSdpChanged: msg= SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport=24611 Require: timer Contact: sip:999@10.0.0.13:8810;+sip.instance="" To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH Content-Type: application/sdp Supported: replaces, answermode, eventlist, outbound, path, timer User-Agent: PortSIP UC - voicemail 12.6.0 Allow-Events: hold, talk, conference, dialog Content-Length: 415
DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::SessionRefresh: duration=90 seq=2 INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::Connected -> InviteSession::SentReinvite DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---01222a1f5d7a586f;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
v=0 o=- 1666772425 1 IN IP4 10.0.0.13 s=ps c=IN IP4 192.168.144.190 t=0 0 m=audio 45006 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2540 | InviteSession::SentReinvite: startStaleReInviteTimer DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::StaleReInvite: duration=40 seq=4 ms=40000 INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2596 | sessionRefresh: Sending SipReq: INVITE 6300@222.129.52.131:25742 tid=01222a1f5d7a586f cseq=3 INVITE contact=2851 / 3 from(tu) DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer B tid=b451a35f639b6d36 ms=32000 DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport Max-Forwards: 70 Contact: sip:2851@192.168.144.190:24613;ob;transport=tls To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
v=0 o=- 1666772425 1 IN IP4 10.0.0.13 s=ps c=IN IP4 192.168.144.190 t=0 0 m=audio 45006 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv sigcomp id= DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: INVITE 6300@222.129.52.131:25742 tid=b451a35f639b6d36 cseq=3 INVITE contact=2851 / 3 from(tu) DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:195 | ConnectionBase::process setting source [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Transport.cxx:401 | incoming from: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:451 | ##Connection: CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] received: SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH Content-Type: application/sdp Supported: replaces, answermode, eventlist, outbound, path User-Agent: PortSIP UC Client iOS - v11.2 Allow-Events: hold, talk, conference, dialog Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel Content-Length: 425
v=0 o=- 1666772365 3 IN IP4 222.129.52.131 s=ps c=IN IP4 222.129.52.131 t=0 0 m=audio 10000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=sendrecv
DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH Content-Type: application/sdp Supported: replaces, answermode, eventlist, outbound, path User-Agent: PortSIP UC Client iOS - v11.2 Allow-Events: hold, talk, conference, dialog Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel Content-Length: 425
DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=1342 DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer StaleClient tid=b451a35f639b6d36 ms=32000 DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1627 | Got: SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1641 | DialogUsageManager::process: found SipMessage DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2177 | DialogUsageManager::processResponse:
SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:485 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:459 | Dialog::dispatch: SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP | 0x7f1c0c1cf700 | SipMessage.cxx:1067 | SipMessage::getContents: application/sdp DEBUG | 20221026-162326.005 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
INFO | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::SentReinvite -> InviteSession::Connected DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::SessionRefresh: duration=90 seq=3 ms=90000 DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 0 ACK Content-Length: 0
DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::CanDiscardAck: duration=32000 seq=3 ms=32000 INFO | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:3234 | Sending SipReq: ACK 6300@222.129.52.131:25742 tid=840f2e7649f9a70c cseq=3 ACK contact=2851 / 3 from(tu) DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 0
DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer Stateless tid=840f2e7649f9a70c ms=32000 DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: ACK 6300@222.129.52.131:25742 tid=840f2e7649f9a70c cseq=3 ACK contact=2851 / 3 from(tu) DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:63 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d- DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] INFO | 20221026-162326.006 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/DialogInfoSubscriptionHandler.cxx:111 | DialogInfoSubscriptionHandler::onConfirmed, entity=sip:6300@example.com, id=urn:uuid:b74b74cc-84ca-42c1-4314-d6a926b15580, localIdentity=sip:2851@example.com, remoteIdentity=sip:6300@example.com, callId=qzWTHr4tu7gz5ottpUMH7Q.., localTag=9ccbac2d, remoteTag=b356a44a DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162326.006 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: sip:2851@192.168.144.190:24613;ob;transport=tls To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 0
sigcomp id= DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write INFO | 20221026-162326.006 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/B2BSession.cxx:5984 | B2BSession[3] onRemoteSdpChanged: msg= SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH Content-Type: application/sdp Supported: replaces, answermode, eventlist, outbound, path User-Agent: PortSIP UC Client iOS - v11.2 Allow-Events: hold, talk, conference, dialog Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel" P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel Content-Length: 425
INFO | 20221026-162338.124 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:84 | Stats message WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | StatisticsMessage.cxx:98 | RESIP:TRANSACTION TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 4 SERVERTX 0 TIMERS 6 Transaction summary: reqi 59 reqo 22 rspi 15 rspo 60 Details: INVi 4/S2/F2 INVo 7/S6/F0 ACKi 4 ACKo 6 BYEi 1/S1/F0 BYEo 1/S1/F0 CANi 0/S1/F0 CANo 1/S1/F0 MSGi 2/S1/F1 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 28/S18/F10 REGo 0/S0/F0 PUBi 2/S1/F1 PUBo 0/S0/F0 SUBi 18/S4/F14 SUBo 0/S0/F0 NOTi 0/S0/F0 NOTo 7/S4/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 WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:104 | FIFO STATISTICS WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TlsTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TlsTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | UdpTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | UdpTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | WssTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | WssTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TransactionController::mStateMacFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=9 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | SipStack::mTUFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.395 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write
版本12.7
每次voicemail时间一长就导致死机。
内存1G，是少了点，但是，出问题时似乎没有记录什么原因。 后来在稍大一点的机器上试了，没有问题。
很奇怪，portsip是docker安装，为什么会把主机弄宕机了？
root@asip:/var/lib/portsip/log# tail callmanager.log -f DEBUG | 20221026-162226.270 | callmanager | RESIP | 0x7f1c0c1cf700 | SipStack.cxx:842 | SEND: SipResp: 200 tid=c26c945c9f8ad676 cseq=5 REGISTER contact=6300@222.129.52.131:25742 / 5 from(tu) DEBUG | 20221026-162226.270 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/PresenceSubscriptionHandler.cxx:473 | PresenceSubscriptionHandler::checkRegistrationStateChanged: registration state unchanged for aor=sip:6300@example.com DEBUG | 20221026-162226.270 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2529 | Got merged request removal request DEBUG | 20221026-162226.270 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: ms=32000 DEBUG | 20221026-162226.271 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
SIP/2.0 200 OK Via: SIP/2.0/TLS 192.168.3.10:5960;branch=z9hG4bK-524287-1---c26c945c9f8ad676;rport=25742;received=222.129.52.131 Require: outbound Contact: sip:6300@222.129.52.131:25742;transport=tls;+sip.instance="";reg-id=1;expires=300;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
To: "Papa"sip:6300@example.com;tag=748ae517
From: "Papa"sip:6300@example.com;tag=f3a69960
Call-ID: NjViQH2JpUyufHT693lbmw..
CSeq: 5 REGISTER
Date: Wed, 26 Oct 2022 08:22:26 GMT
User-Agent: PortSIP UC - Call Manager 12.7.2.11
x-p-push: enabled=true;id=+TGE1aKu78Jif4qvZXdfv+KlsbSju3gA4lrBQ08T8mqC+bYCA6YCM39QoImlADU4
Content-Length: 0
sigcomp id= DEBUG | 20221026-162226.271 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162226.270 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:124 | ** DialogSet::~DialogSet: NjViQH2JpUyufHT693lbmw..-072a2730***** DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
KeepAliveTimeout V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 DEBUG | 20221026-162226.325 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=2: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ], interval=90s, supportsOutbound=false, refCount=2 DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
sigcomp id= DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162226.325 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ms=90000 DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:473 | Received response CRLF (aka pong). DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=2 DEBUG | 20221026-162226.733 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.733 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1387 | keepalive pong received from [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162226.733 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:154 | Received pong response for keep alive id=2: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162227.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::CanDiscardAck: duration=32000 seq=2 DEBUG | 20221026-162227.769 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::CanDiscardAck: duration=32000 seq=2 DEBUG | 20221026-162229.782 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleCall: duration=180 seq=2 DEBUG | 20221026-162235.534 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleReInvite: duration=40 seq=2 DEBUG | 20221026-162235.534 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=1
DumTimeout::StaleReInvite: duration=40 seq=2 INFO | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:532 | Exception on socket 100 code: 110; closing connection ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:164 | Unexpected error in SSL_shutdown ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:47 | error:140E0114:SSL routines:SSL_shutdown:uninitialized DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:48 | Error code = 336462100 file=../ssl/ssl_lib.c line=2079 ERR | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:51 | Got TLS SSL_shutdown error=1 ret=-1 DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:217 | ConnectionManager::removeConnection() DEBUG | 20221026-162235.806 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:115 | ConnectionBase::~ConnectionBase 0x7f1c4c02d6f0 INFO | 20221026-162235.806 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:102 | Sending ConnectionTerminated [ V4 222.129.52.131:25794 TLS targetDomain=222.129.52.131 flowKey=100 transportKey=1 ] to TUs DEBUG | 20221026-162235.806 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1479 | connection terminated message DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162245.779 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162258.272 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DEBUG | 20221026-162258.273 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2538 | Merged request removed INFO | 20221026-162308.103 | callmanager | PortPBX | 0x7f1c101d7700 | ../../portpbx/CDRManager.cxx:165 | === Processed CDR, total = 13, CDR Fifo size = 0 DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162310.905 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162325.416 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::StaleCall: duration=180 seq=2 DEBUG | 20221026-162325.422 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
KeepAliveTimeout V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 DEBUG | 20221026-162325.423 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | KeepAliveManager.cxx:100 | Refreshing keepalive for id=4: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ], interval=60s, supportsOutbound=false, refCount=1 DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
sigcomp id= DEBUG | 20221026-162325.423 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: KeepAliveTimeout V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ms=60000 DEBUG | 20221026-162325.532 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::SessionRefresh: duration=90 seq=2 INFO | 20221026-162325.532 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::Connected -> InviteSession::SentReinvite DEBUG | 20221026-162325.532 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---cfdafa52c5e63a7f;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
v=0 o=- 1666772365 1 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 45004 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:k6h6iqruNLenXQjEXR+MeN80ELDOe0KYOUiPq83D a=encryption:optional a=sendrecv
INFO | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2540 | InviteSession::SentReinvite: startStaleReInviteTimer DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::StaleReInvite: duration=40 seq=4 ms=40000 INFO | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2596 | sessionRefresh: Sending SipReq: INVITE 999@10.0.0.13:8810 tid=cfdafa52c5e63a7f cseq=3 INVITE contact=6300 / 3 from(tu) DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---f365b00e57338876;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
v=0 o=- 1666772365 1 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 45004 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:k6h6iqruNLenXQjEXR+MeN80ELDOe0KYOUiPq83D a=encryption:optional a=sendrecv
DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer B tid=f365b00e57338876 ms=32000 DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.533 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer A tid=f365b00e57338876 ms=500 DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
INVITE sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport Max-Forwards: 70 Contact: sip:6300@10.0.0.13:24611;ob To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 portsip-vm-receiver: sip:2851@example.com X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 522
v=0 o=- 1666772365 1 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 45004 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:k6h6iqruNLenXQjEXR+MeN80ELDOe0KYOUiPq83D a=encryption:optional a=sendrecv sigcomp id= DEBUG | 20221026-162325.533 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: INVITE 999@10.0.0.13:8810 tid=f365b00e57338876 cseq=3 INVITE contact=6300 / 3 from(tu) DEBUG | 20221026-162325.534 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Transport.cxx:401 | incoming from: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport=24611 Require: timer Contact: sip:999@10.0.0.13:8810;+sip.instance=""
To: sip:999@example.com;tag=eae52c4b
From: "Papa"sip:6300@example.com;tag=ab361634
Call-ID: QpW70BheA9J8X9LYPWm1_A..
CSeq: 3 INVITE
Session-Expires: 180;refresher=uac
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path, timer
User-Agent: PortSIP UC - voicemail 12.6.0
Allow-Events: hold, talk, conference, dialog
Content-Length: 415
v=0 o=- 1666772425 3 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 39000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1627 | Got: SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1641 | DialogUsageManager::process: found SipMessage DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2177 | DialogUsageManager::processResponse:
SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:485 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport=24611 Require: timer Contact: sip:999@10.0.0.13:8810;+sip.instance=""
To: sip:999@example.com;tag=eae52c4b
From: "Papa"sip:6300@example.com;tag=ab361634
Call-ID: QpW70BheA9J8X9LYPWm1_A..
CSeq: 3 INVITE
Session-Expires: 180;refresher=uac
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path, timer
User-Agent: PortSIP UC - voicemail 12.6.0
Allow-Events: hold, talk, conference, dialog
Content-Length: 415
v=0 o=- 1666772425 3 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 39000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:459 | Dialog::dispatch: SipResp: 200 tid=f365b00e57338876 cseq=3 INVITE contact=999@10.0.0.13:8810 / 3 from(wire) DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | SipMessage.cxx:1067 | SipMessage::getContents: application/sdp DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
INFO | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::SentReinvite -> InviteSession::Connected DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::SessionRefresh: duration=90 seq=3 ms=90000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 0 ACK Content-Length: 0
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::CanDiscardAck: duration=32000 seq=3 ms=32000 INFO | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:3234 | Sending SipReq: ACK 999@10.0.0.13:8810 tid=ffbdbf0a5d49fa01 cseq=3 ACK contact=6300 / 3 from(tu) DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: <sip:6300;ob> To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 0
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer StaleClient tid=f365b00e57338876 ms=32000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634-eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer Stateless tid=ffbdbf0a5d49fa01 ms=32000 DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: ACK 999@10.0.0.13:8810 tid=ffbdbf0a5d49fa01 cseq=3 ACK contact=6300 / 3 from(tu) DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] DEBUG | 20221026-162325.535 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:63 | DialogId::DialogId: QpW70BheA9J8X9LYPWm1_A..-ab361634- DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24611 UDP flowKey=29 transportKey=3 ] INFO | 20221026-162325.535 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/DialogInfoSubscriptionHandler.cxx:111 | DialogInfoSubscriptionHandler::onConfirmed, entity=sip:999@example.com, id=urn:uuid:eb7ccf62-c37c-4f4d-7e74-86e819f7490c, localIdentity=sip:6300@example.com, remoteIdentity=sip:999@example.com, callId=QpW70BheA9J8X9LYPWm1_A.., localTag=ab361634, remoteTag=eae52c4b DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] -> [ V4 10.0.0.13:0 UDP flowKey=29 transportKey=3 ] sent-by= sent-port=0 DEBUG | 20221026-162325.535 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
DEBUG | 20221026-162325.535 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 10.0.0.13:8810 UDP flowKey=29 transportKey=3 ] tlsDomain= via [ V4 10.0.0.13:24611 UDP flowKey=29 transportKey=3 ]
ACK sip:999@10.0.0.13:8810 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---ffbdbf0a5d49fa01;rport Max-Forwards: 70 Contact: sip:6300@10.0.0.13:24611;ob To: sip:999@example.com;tag=eae52c4b From: "Papa"sip:6300@example.com;tag=ab361634 Call-ID: QpW70BheA9J8X9LYPWm1_A.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: qzWTHr4tu7gz5ottpUMH7Q.. X-Session-Id: 637565939482628096 Content-Length: 0
sigcomp id= INFO | 20221026-162325.535 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/B2BSession.cxx:5984 | B2BSession[5] onRemoteSdpChanged: msg= SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.13:24611;branch=z9hG4bK-524287-1---f365b00e57338876;rport=24611 Require: timer Contact: sip:999@10.0.0.13:8810;+sip.instance=""
To: sip:999@example.com;tag=eae52c4b
From: "Papa"sip:6300@example.com;tag=ab361634
Call-ID: QpW70BheA9J8X9LYPWm1_A..
CSeq: 3 INVITE
Session-Expires: 180;refresher=uac
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path, timer
User-Agent: PortSIP UC - voicemail 12.6.0
Allow-Events: hold, talk, conference, dialog
Content-Length: 415
v=0 o=- 1666772425 3 IN IP4 10.0.0.13 s=ps c=IN IP4 10.0.0.13 t=0 0 m=audio 39000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0c9d0700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
DumTimeout::SessionRefresh: duration=90 seq=2 INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::Connected -> InviteSession::SentReinvite DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---01222a1f5d7a586f;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
v=0 o=- 1666772425 1 IN IP4 10.0.0.13 s=ps c=IN IP4 192.168.144.190 t=0 0 m=audio 45006 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2540 | InviteSession::SentReinvite: startStaleReInviteTimer DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::StaleReInvite: duration=40 seq=4 ms=40000 INFO | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2596 | sessionRefresh: Sending SipReq: INVITE 6300@222.129.52.131:25742 tid=01222a1f5d7a586f cseq=3 INVITE contact=2851 / 3 from(tu) DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer B tid=b451a35f639b6d36 ms=32000 DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
v=0 o=- 1666772425 1 IN IP4 10.0.0.13 s=ps c=IN IP4 192.168.144.190 t=0 0 m=audio 45006 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv
DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162325.768 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
INVITE sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport Max-Forwards: 70 Contact: sip:2851@192.168.144.190:24613;ob;transport=tls To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 INVITE Session-Expires: 180;refresher=uac Min-SE: 90 Accept-Language: en Allow: REGISTER, INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, PUBLISH Content-Type: application/sdp Supported: replaces, norefersub, tdialog, timer User-Agent: PortSIP UC - Call Manager 12.7.2.11 P-Asserted-Identity: sip:999@example.com Remote-Party-ID: sip:999@example.com X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 420
v=0 o=- 1666772425 1 IN IP4 10.0.0.13 s=ps c=IN IP4 192.168.144.190 t=0 0 m=audio 45006 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:BrPidN25rEDaNeYa6o7c4oxjnNOsgKyNyoBVpGFM a=sendrecv sigcomp id= DEBUG | 20221026-162325.769 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write DEBUG | 20221026-162325.768 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: INVITE 6300@222.129.52.131:25742 tid=b451a35f639b6d36 cseq=3 INVITE contact=2851 / 3 from(tu) DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:195 | ConnectionBase::process setting source [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Transport.cxx:401 | incoming from: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:451 | ##Connection: CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] received: SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
To: "Papa"sip:6300@example.com;tag=b356a44a
From: "Papa"sip:2851@example.com;tag=9ccbac2d
Call-ID: qzWTHr4tu7gz5ottpUMH7Q..
CSeq: 3 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path
User-Agent: PortSIP UC Client iOS - v11.2
Allow-Events: hold, talk, conference, dialog
Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel
Content-Length: 425
v=0 o=- 1666772365 3 IN IP4 222.129.52.131 s=ps c=IN IP4 222.129.52.131 t=0 0 m=audio 10000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=sendrecv
DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:70 | Send to TU: DialogUsageManager size=0
SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
To: "Papa"sip:6300@example.com;tag=b356a44a
From: "Papa"sip:2851@example.com;tag=9ccbac2d
Call-ID: qzWTHr4tu7gz5ottpUMH7Q..
CSeq: 3 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path
User-Agent: PortSIP UC Client iOS - v11.2
Allow-Events: hold, talk, conference, dialog
Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel
Content-Length: 425
v=0 o=- 1666772365 3 IN IP4 222.129.52.131 s=ps c=IN IP4 222.129.52.131 t=0 0 m=audio 10000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=sendrecv
DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=1342 DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer StaleClient tid=b451a35f639b6d36 ms=32000 DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1627 | Got: SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1641 | DialogUsageManager::process: found SipMessage DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:2177 | DialogUsageManager::processResponse:
SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogSet.cxx:485 | Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
To: "Papa"sip:6300@example.com;tag=b356a44a
From: "Papa"sip:2851@example.com;tag=9ccbac2d
Call-ID: qzWTHr4tu7gz5ottpUMH7Q..
CSeq: 3 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path
User-Agent: PortSIP UC Client iOS - v11.2
Allow-Events: hold, talk, conference, dialog
Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel
Content-Length: 425
v=0 o=- 1666772365 3 IN IP4 222.129.52.131 s=ps c=IN IP4 222.129.52.131 t=0 0 m=audio 10000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=sendrecv
DEBUG | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:459 | Dialog::dispatch: SipResp: 200 tid=b451a35f639b6d36 cseq=3 INVITE contact=6300@222.129.52.131:25742 / 3 from(wire) tlsd=example.com DEBUG | 20221026-162326.005 | callmanager | RESIP | 0x7f1c0c1cf700 | SipMessage.cxx:1067 | SipMessage::getContents: application/sdp DEBUG | 20221026-162326.005 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
INFO | 20221026-162326.005 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:2892 | Transition InviteSession::SentReinvite -> InviteSession::Connected DEBUG | 20221026-162326.005 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::SessionRefresh: duration=90 seq=3 ms=90000 DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | Dialog.cxx:1070 | Dialog::makeRequest:
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 0 ACK Content-Length: 0
DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0c1cf700 | TimerQueue.cxx:124 | Adding application timer: DumTimeout::CanDiscardAck: duration=32000 seq=3 ms=32000 INFO | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | InviteSession.cxx:3234 | Sending SipReq: ACK 6300@222.129.52.131:25742 tid=840f2e7649f9a70c cseq=3 ACK contact=2851 / 3 from(tu) DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1011 | SEND:
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/ ;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: <sip:2851;ob> To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 0
DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:50 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d-b356a44a DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TimerQueue.cxx:50 | Adding timer: Timer Stateless tid=840f2e7649f9a70c ms=32000 DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogUsageManager.cxx:1172 | Send: SipReq: ACK 6300@222.129.52.131:25742 tid=840f2e7649f9a70c cseq=3 ACK contact=2851 / 3 from(tu) DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TransactionState.cxx:2635 | Sending to tuple: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] DEBUG | 20221026-162326.006 | callmanager | RESIP:DUM | 0x7f1c0c1cf700 | DialogId.cxx:63 | DialogId::DialogId: qzWTHr4tu7gz5ottpUMH7Q..-9ccbac2d- DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:984 | Found transport: [ V4 0.0.0.0:24613 TLS transportKey=1 ] DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | TcpBaseTransport.cxx:330 | Processing write for [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] INFO | 20221026-162326.006 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/DialogInfoSubscriptionHandler.cxx:111 | DialogInfoSubscriptionHandler::onConfirmed, entity=sip:6300@example.com, id=urn:uuid:b74b74cc-84ca-42c1-4314-d6a926b15580, localIdentity=sip:2851@example.com, remoteIdentity=sip:6300@example.com, callId=qzWTHr4tu7gz5ottpUMH7Q.., localTag=9ccbac2d, remoteTag=b356a44a DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:920 | Looked up source for destination: [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] -> [ V4 10.0.0.13:0 TLS flowKey=97 transportKey=1 ] sent-by= sent-port=0 DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionManager.cxx:65 | Found fd 97 DEBUG | 20221026-162326.006 | callmanager | RESIP | 0x7f1c0c1cf700 | Helper.cxx:2235 | Got sdp
DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d9d2700 | TransportSelector.cxx:1340 | Transmitting to [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] tlsDomain= via [ V4 10.0.0.13:24613 TLS flowKey=97 transportKey=1 ]
ACK sip:6300@222.129.52.131:25742;transport=tls;ob SIP/2.0 Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---840f2e7649f9a70c;rport Max-Forwards: 70 Contact: sip:2851@192.168.144.190:24613;ob;transport=tls To: "Papa"sip:6300@example.com;tag=b356a44a From: "Papa"sip:2851@example.com;tag=9ccbac2d Call-ID: qzWTHr4tu7gz5ottpUMH7Q.. CSeq: 3 ACK User-Agent: PortSIP UC - Call Manager 12.7.2.11 X-CID: QpW70BheA9J8X9LYPWm1_A.. X-Session-Id: 637565939482628096 Content-Length: 0
sigcomp id= DEBUG | 20221026-162326.006 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write INFO | 20221026-162326.006 | callmanager | PortPBX | 0x7f1c0c1cf700 | ../../portpbx/B2BSession.cxx:5984 | B2BSession[3] onRemoteSdpChanged: msg= SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.13:24613;branch=z9hG4bK-524287-1---b451a35f639b6d36;rport=24613;received=192.168.144.190 Contact: sip:6300@222.129.52.131:25742;transport=tls;ob;+sip.instance="";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
To: "Papa"sip:6300@example.com;tag=b356a44a
From: "Papa"sip:2851@example.com;tag=9ccbac2d
Call-ID: qzWTHr4tu7gz5ottpUMH7Q..
CSeq: 3 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO, PUBLISH
Content-Type: application/sdp
Supported: replaces, answermode, eventlist, outbound, path
User-Agent: PortSIP UC Client iOS - v11.2
Allow-Events: hold, talk, conference, dialog
Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel
Content-Length: 425
v=0 o=- 1666772365 3 IN IP4 222.129.52.131 s=ps c=IN IP4 222.129.52.131 t=0 0 m=audio 10000 RTP/SAVP 105 18 8 0 101 a=rtpmap:105 opus/48000/2 a=fmtp:105 useinbandfec=1 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=mid:audio a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:TwrSDxNDAQoymzLc1QzuMiOITyzJ5NxTb41YAmAo a=sendrecv
INFO | 20221026-162338.124 | callmanager | RESIP:TRANSACTION | 0x7f1c0d9d2700 | TuSelector.cxx:84 | Stats message WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | StatisticsMessage.cxx:98 | RESIP:TRANSACTION TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 4 SERVERTX 0 TIMERS 6 Transaction summary: reqi 59 reqo 22 rspi 15 rspo 60 Details: INVi 4/S2/F2 INVo 7/S6/F0 ACKi 4 ACKo 6 BYEi 1/S1/F0 BYEo 1/S1/F0 CANi 0/S1/F0 CANo 1/S1/F0 MSGi 2/S1/F1 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 28/S18/F10 REGo 0/S0/F0 PUBi 2/S1/F1 PUBo 0/S0/F0 SUBi 18/S4/F14 SUBo 0/S0/F0 NOTi 0/S0/F0 NOTo 7/S4/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 WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:104 | FIFO STATISTICS WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TlsTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TlsTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | UdpTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:149 | In State: NewMessage WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | UdpTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:159 | Got incoming double-CRLF keepalive (aka ping). WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | WssTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:465 | Sending response CRLF (aka pong). WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | WssTransport::mTxFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | Connection.cxx:434 | Connection::performReads() read=4 WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | TransactionController::mStateMacFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=9 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.188 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ConnectionBase.cxx:1069 | Creating buffer for CONN_BASE: 0x7f1c4c01a980 [ V4 222.129.52.131:25742 TLS flowKey=97 transportKey=1 ] WARNING | 20221026-162338.124 | callmanager | RESIP:STATS | 0x7f1c0d9d2700 | GeneralCongestionManager.cxx:114 | SipStack::mTUFifo: Size=0 TimeDepth(sec)=0 ExpWait(msec)=0 AvgSvcTime(usec)=0 Metric=WAIT_TIME MaxTolerance=200 CurBehavior=NORMAL DEBUG | 20221026-162338.395 | callmanager | RESIP:TRANSPORT | 0x7f1c0d1d1700 | ssl/TlsConnection.cxx:507 | Transportwrite--Up fall through to write