Failed to call kamailio's rtpengine_subscribe_answer

11 views
Skip to first unread message

xiaofang han (hance)

unread,
Mar 5, 2026, 9:14:09 PM (9 days ago) Mar 5
to Sipwise rtpengine
Failed to call kamailio's rtpengine_subscribe_answer

The following information is in rtpengine.log:
Protocol error in packet from 127.0.0.1:58489: Failed to process subscription answer


Os: Debian 12.13
RTPEngine Version: 13.5.2.0+0~mr13.5.2.0 git-mr13.5-4fb410a2
Kamailio Version: 6.1.1
SIPp Version: 3.6.1
Architecture: x86_64
SIPp acts as SRS


sipp.xml:

```
<?xml version="1.0" encoding="UTF-8" ?>
<!DOCTYPE scenario SYSTEM "sipp.dtd">

<!-- This program is free software; you can redistribute it and/or      -->
<!-- modify it under the terms of the GNU General Public License as     -->
<!-- published by the Free Software Foundation; either version 2 of the -->
<!-- License, or (at your option) any later version.                    -->
<!--                                                                    -->
<!-- This program is distributed in the hope that it will be useful,    -->
<!-- but WITHOUT ANY WARRANTY; without even the implied warranty of     -->
<!-- MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the      -->
<!-- GNU General Public License for more details.                       -->
<!--                                                                    -->
<!-- You should have received a copy of the GNU General Public License  -->
<!-- along with this program; if not, write to the                      -->
<!-- Free Software Foundation, Inc.,                                    -->
<!-- 59 Temple Place, Suite 330, Boston, MA  02111-1307 USA             -->
<!--                                                                    -->
<!--                 Sipp default 'uas' scenario.                       -->
<!--                                                                    -->

<scenario name="Basic UAS responder">
  <!-- By adding rrs="true" (Record Route Sets), the route sets         -->
  <!-- are saved and used for following messages sent. Useful to test   -->
  <!-- against stateful SIP proxies/B2BUAs.                             -->
  <!-- Adding ignoresdp="true" here would ignore the SDP data: that     -->
  <!-- can be useful if you want to reject reINVITEs and keep the       -->
  <!-- media stream flowing.                                            -->
  <recv request="INVITE" crlf="true" rrs="true">
  </recv>

  <!-- The '[last_*]' keyword is replaced automatically by the          -->
  <!-- specified header if it was present in the last message received  -->
  <!-- (except if it was a retransmission). If the header was not       -->
  <!-- present or if no message has been received, the '[last_*]'       -->
  <!-- keyword is discarded, and all bytes until the end of the line    -->
  <!-- are also discarded.                                              -->
  <!--                                                                  -->
  <!-- If the specified header was present several times in the         -->
  <!-- message, all occurrences are concatenated (CRLF separated)       -->
  <!-- to be used in place of the '[last_*]' keyword.                   -->

  <send>
    <![CDATA[

      SIP/2.0 180 Ringing
      [last_Via:]
      [last_From:]
      [last_To:];tag=[pid]SIPpTag01[call_number]
      [last_Call-ID:]
      [last_Record-Route:]
      [last_CSeq:]
      Contact: <sip:[local_ip]:[local_port];transport=[transport]>
      Content-Length: 0

    ]]>
  </send>

  <send retrans="500">
    <![CDATA[

      SIP/2.0 200 OK
      [last_Via:]
      [last_From:]
      [last_To:];tag=[pid]SIPpTag01[call_number]
      [last_Call-ID:]
      [last_Record-Route:]
      [last_CSeq:]
      Contact: <sip:[local_ip]:[local_port];transport=[transport]>
      Content-Type: application/sdp
      Content-Length: [len]

      v=0
      o=user1 53655765 2353687637 IN IP[local_ip_type] [local_ip]
      s=-
      t=0 0
      m=audio 6000 RTP/AVP 0
      c=IN IP[media_ip_type] [media_ip]
      a=label:0
      a=rtpmap:0 PCMU/8000
      a=rtcp:6001
      a=recvonly
      m=audio 6002 RTP/AVP 0
      c=IN IP[media_ip_type] [media_ip]
      a=label:1
      a=rtpmap:0 PCMU/8000
      a=rtcp:6003  
      a=inactive

    ]]>
  </send>

  <recv request="ACK"
        rtd="true"
        crlf="true">
  </recv>



  <recv request="BYE">
  </recv>

  <send>
    <![CDATA[

      SIP/2.0 200 OK
      [last_Via:]
      [last_From:]
      [last_To:]
      [last_Call-ID:]
      [last_CSeq:]
      Contact: <sip:[local_ip]:[local_port];transport=[transport]>
      Content-Length: 0

    ]]>
  </send>

  <!-- Keep the call open for a while in case the 200 is lost to be     -->
  <!-- able to retransmit it if we receive the BYE again.               -->
  <timewait milliseconds="4000"/>


  <!-- definition of the response time repartition table (unit is ms)   -->
  <ResponseTimeRepartition value="10, 20, 30, 40, 50, 100, 150, 200"/>

  <!-- definition of the call length repartition table (unit is ms)     -->
  <CallLengthRepartition value="10, 50, 100, 500, 1000, 5000, 10000"/>

</scenario>
```



rtpengine.log:

``
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Received command 'offer' from 127.0.0.1:48432
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Dump for 'offer' from 127.0.0.1:48432: { "supports": [ "load limit" ], "sdp": "v=0#015#012o=- 3 2 IN IP4 192.168.1.171#015#012s=CounterPath eyeBeam 1.5#015#012c=IN IP4 192.168.1.171#015#012t=0 0#015#012m=audio 61896 RTP/AVP 0 8 18 101#015#012a=fmtp:18 annexb=no#015#012a=fmtp:101 0-15#015#012a=rtpmap:18 G729/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=sendrecv#015#012a=x-rtp-session-id:E7C60C0E2C144A7E81148B40486526F9#015#012", "received-from": [ "IP4", "192.168.1.171" ], "call-id": "MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.", "from-tag": "f97 ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 5ad3e", "command": "offer", "sip-message-type": "sip_request" }
NOTICE: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Creating new call
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] No matching media (index: 1) using subscription, just use an index.
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag '' (index: 1) to media with monologue tag 'f975ad3e' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag 'f975ad3e' (index: 1) to media with monologue tag '' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] setting other slot to 0, setting slot to 0
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating codecs for offerer f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMA/8000/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec G729/8000/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec telephone-event/8000/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating offer codecs for answerer  #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMA/8000/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec G729/8000/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec telephone-event/8000/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating supplemental codecs for  #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for  #1 -> f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMA/8000/1/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for PCMA/8000/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMA/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec G729/8000/1/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for G729/8000/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for G729/8000/annexb=no is G729/8000/1/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec G729/8000/annexb=no (18) for passthrough (to 18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for G729/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for G729/8000/annexb=no (18) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for telephone-event/8000/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-15 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-15 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating supplemental codecs for  #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for  #1 -> f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMA/8000/1/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMA/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec G729/8000/1/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for G729/8000/annexb=no is G729/8000/1/annexb=no (18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec G729/8000/annexb=no (18) for passthrough (to 18)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for G729/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for G729/8000/annexb=no (18) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-15 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-15 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '1')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '2')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '3')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '39678'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '39679'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Opened 2 socket(s) from port '39678' (on interface '192.168.1.118') for a media relay
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '1')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '31200'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '31201'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Opened 2 socket(s) from port '31200' (on interface '192.168.1.118') for a media relay
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] set FILLED flag for stream, local 192.168.1.118:31200 remote 192.168.1.171:61896
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] set FILLED flag for stream, local 192.168.1.118:31201 remote 192.168.1.171:61897
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Replying to 'offer' from 127.0.0.1:48432 (elapsed time 0.003997 sec)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Response dump for 'offer' to 127.0.0.1:48432: { "sdp": "v=0#015#012o=- 3 2 IN IP4 192.168.1.171#015#012s=CounterPath eyeBeam 1.5#015#012t=0 0#015#012m=audio 39678 RTP/AVP 0 8 18 101#015#012c=IN IP4 192.168.1.118#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:18 G729/8000#015#012a=fmtp:18 annexb=no#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=x-rtp-session-id:E7C60C0E2C144A7E81148B40486526F9#015#012a=sendrecv#015#012a=rtcp:39679#015#012", "result": "ok" }
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] New ingress SSRC for: :0 SSRC: 4bd14564
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] Handling packet: remote 192.168.1.171:4029 (expected: ) -> local 192.168.1.118:39679
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] New egress (direct) SSRC for: 192.168.1.171:61897 SSRC: 4bd14564
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [rtcp] Calling handler for RTCP packet type 201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] RR from 4bd14564 about 0: FL 0 TL 0 HSR 0 J 0 LSR 0 DLSR 0
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] No payload type known for RTCP RR, discarding
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Forward to sink endpoint: local 192.168.1.118:31201 -> remote 192.168.1.171:61897
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] Handling packet: remote 192.168.1.171:4029 (expected: ) -> local 192.168.1.118:39679
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [rtcp] Calling handler for RTCP packet type 201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] RR from 4bd14564 about 0: FL 0 TL 0 HSR 0 J 0 LSR 0 DLSR 0
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [core] No payload type known for RTCP RR, discarding
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.//1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Forward to sink endpoint: local 192.168.1.118:31201 -> remote 192.168.1.171:61897
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Received command 'answer' from 127.0.0.1:36943
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Dump for 'answer' from 127.0.0.1:36943: { "supports": [ "load limit" ], "sdp": "v=0#015#012o=- 3981779720 3981779721 IN IP4 192.168.1.171#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4028 RTP/AVP 0 101#015#012c=IN IP4 192.168.1.171#015#012b=TIAS:64000#015#012a=rtcp:4029 IN IP4 192.168.1.171#015#012a=sendrecv#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-16#015#012a=ssrc:1272005988 cname:6e53039c5df71472#015#012", "received-from": [ "IP4", "192.168.1.171" ], "call-id": "MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 1ZmM5OTk5NDc.", "from-tag": "f975ad3e", "to-tag": "62d8c687a95247debaae81d06cf02a7b", "command": "answer", "sip-message-type": "sip_reply" }
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unconfirming peer address for local 192.168.1.118:39678 (dialogue signalling event)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unconfirming peer address for local 192.168.1.118:31200 (dialogue signalling event)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unconfirming peer address for local 192.168.1.118:31201 (dialogue signalling event)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unconfirming peer address for local 192.168.1.118:39679 (dialogue signalling event)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unsubscribing media with monologue tag 'f975ad3e' (index: 1) from media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unsubscribing media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1) from media with monologue tag 'f975ad3e' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag 'f975ad3e' (index: 1) to media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1) to media with monologue tag 'f975ad3e' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating codecs for answerer 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec telephone-event/8000/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for 62d8c687a95247debaae81d06cf02a7b #1 -> f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-15 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Codec answer for 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Reverse codec for PCMU/8000/ (0) is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Reverse codec for telephone-event/8000/0-16 (101) is telephone-event/8000/0-15 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] telephone-event/8000/1 payload type 101 already present, skip
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] telephone-event/8000/1 payload type 101 already present, skip
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for f975ad3e #1 -> 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for telephone-event/8000/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-16 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating supplemental codecs for f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Updating supplemental codecs for 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for f975ad3e #1 -> 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-16 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for 62d8c687a95247debaae81d06cf02a7b #1 -> f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-15 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] set FILLED flag for stream, local 192.168.1.118:39678 remote 192.168.1.171:4028
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] set FILLED flag for stream, local 192.168.1.118:39679 remote 192.168.1.171:4029
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Replying to 'answer' from 127.0.0.1:36943 (elapsed time 0.001143 sec)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Response dump for 'answer' to 127.0.0.1:36943: { "sdp": "v=0#015#012o=- 3981779720 3981779721 IN IP4 192.168.1.171#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 31200 RTP/AVP 0 101#015#012c=IN IP4 192.168.1.118#015#012b=TIAS:64000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-16#015#012a=ssrc:1272005988 cname:6e53039c5df71472#015#012a=sendrecv#015#012a=rtcp:31201#015#012", "result": "ok" }
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Received command 'subscribe request' from 127.0.0.1:36943
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Dump for 'subscribe request' from 127.0.0.1:36943: { "supports": [ "load limit" ], "call-id": "MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.", "to-tag": "62d8c687a95247debaae81d06cf02a7b", "command": "subscribe request", "sip-message-type": "sip_reply", "rtpp-flags": "all siprec" }
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Unsubscribing media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1) from media with monologue tag 'f975ad3e' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1) to media with monologue tag 'f975ad3e' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec telephone-event/8000/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for 62d8c687a95247debaae81d06cf02a7b #1 -> f975ad3e #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-15 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Subscribing media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 2) to media with monologue tag '62d8c687a95247debaae81d06cf02a7b' (index: 1)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec telephone-event/8000/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Setting up codec handlers for 62d8c687a95247debaae81d06cf02a7b #2 -> 62d8c687a95247debaae81d06cf02a7b #1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Default sink codec is PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for PCMU/8000/ is PCMU/8000/1/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec PCMU/8000/ (0) for passthrough (to 0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for PCMU/8000/ (0) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Checking receiver codec telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Creating codec handler for telephone-event/8000/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink codec for telephone-event/8000/0-16 is telephone-event/8000/1/0-16 (101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Sink supports codec telephone-event/8000/0-16 (101) for passthrough (to 101)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Using passthrough handler for telephone-event/8000/0-16 (101) with DTMF 101, CN -1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '1')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '2')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '3')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to find RTP/RTCP ports (allocation attempt = '4')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '5')
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '39538'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Trying to bind the socket for port = '39539'
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Opened 2 socket(s) from port '39538' (on interface '192.168.1.118') for a media relay
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Replying to 'subscribe request' from 127.0.0.1:36943 (elapsed time 0.000784 sec)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Response dump for 'subscribe request' to 127.0.0.1:36943: { "sdp": "v=0#015#012o=- 3 2 IN IP4 192.168.1.171#015#012s=CounterPath eyeBeam 1.5#015#012t=0 0#015#012m=audio 39678 RTP/AVP 0 101#015#012c=IN IP4 192.168.1.118#015#012a=label:0#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-16#015#012a=x-rtp-session-id:E7C60C0E2C144A7E81148B40486526F9#015#012a=sendonly#015#012a=rtcp:39679#015#012m=audio 39538 RTP/AVP 0 101#015#012c=IN IP4 192.168.1.118#015#012b=TIAS:64000#015#012a=label:1#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... =fmtp:101 0-16#015#012a=ssrc:1272005988 cname:6e53039c5df71472#015#012a=inactive#015#012a=rtcp:39539#015#012", "tag-medias": [ { "tag": "f975ad3e", "medias": [ { "index": 1, "type": "audio", "label": "0", "mode": "sendrecv" } ] }, { "tag": "62d8c687a95247debaae81d06cf02a7b", "medias": [ { "index": 1, "type": "audio", "label": "0", "mode": "sendonly" }, { "index": 2, "type": "audio", "label": "1", "mode": "inactive" } ] } ], "media-labels": { "0": { "tag": "f975ad3e", "index": 1, "type": "audio", "mode": "sendrecv" }, " ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 0": { "tag": "62d8c687a95247debaae81d06cf02a7b", "index": 1, "type": "audio", "mode": "sendonly" }, "1": { "tag": "62d8c687a95247debaae81d06cf02a7b", "index": 2, "type": "audio", "mode": "inactive" } }, "from-tags": [ "f975ad3e", "62d8c687a95247debaae81d06cf02a7b" ], "to-tag": "62d8c687a95247debaae81d06cf02a7b", "result": "ok" }
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Confirmed peer address as 192.168.1.171:4028
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Handling packet: remote 192.168.1.171:4028 (expected: 192.168.1.171:4028) -> local 192.168.1.118:39678 (RTP seq 11467 TS 160 SSRC 4bd14564)
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Kernelizing media stream: remote 192.168.1.171:4028 -> local 192.168.1.118:39678
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Kernelizing media stream: 192.168.1.171:4028 -> 192.168.1.118:39678 | 192.168.1.118:31200 -> 192.168.1.171:61896
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Kernelizing media stream: 192.168.1.171:4028 -> 192.168.1.118:39678 | 192.168.1.118:31201 -> 192.168.1.171:61897
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Received command 'subscribe answer' from 127.0.0.1:58489
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Dump for 'subscribe answer' from 127.0.0.1:58489: { "supports": [ "load limit" ], "sdp": "v=0#015#012o=user1 53655765 2353687637 IN IP4 192.168.1.118#015#012s=-#015#012t=0 0#015#012m=audio 6000 RTP/AVP 0#015#012c=IN IP4 192.168.1.118#015#012a=label:0#015#012a=rtpmap:0 PCMU/8000#015#012a=rtcp:6001#015#012a=recvonly#015#012m=audio 6002 RTP/AVP 0#015#012c=IN IP4 192.168.1.118#015#012a=label:1#015#012a=rtpmap:0 PCMU/8000#015#012a=rtcp:6003#015#012a=inactive#015#012", "received-from": [ "IP4", "192.168.1.118" ], "call-id": "MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.", "to-tag": "62d ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 8c687a95247debaae81d06cf02a7b", "command": "subscribe answer", "sip-message-type": "sip_reply" }
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Adding codec PCMU/8000/ (0)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [codec] Source codec telephone-event/8000/0-16 is not present in the answer
WARNING: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Protocol error in packet from 127.0.0.1:58489: Failed to process subscription answer [d8:supportsl10:load limite3:sdp279:v=0#015#012o=user1 53655765 2353687637 IN IP4 192.168.1.118#015#012s=-#015#012t=0 0#015#012m=audio 6000 RTP/AVP 0#015#012c=IN IP4 192.168.1.118#015#012a=label:0#015#012a=rtpmap:0 PCMU/8000#015#012a=rtcp:6001#015#012a=recvonly#015#012m=audio 6002 RTP/AVP 0#015#012c=IN IP4 192.168.1.118#015#012a=label:1#015#012a=rtpmap:0 PCMU/8000#015#012a=rtcp:6003#015#012a=inactive#015#01213:received-froml3:IP413:192.168.1.118e7:call-id44:MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk ...
WARNING: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 5NDc.6:to-tag32:62d8c687a95247debaae81d06cf02a7b7:command16:subscribe answer16:sip-message-type9:sip_replye]
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] New ingress SSRC for: 192.168.1.171:61897 SSRC: 25181792
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Confirmed peer address as 192.168.1.171:61897
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Handling packet: remote 192.168.1.171:61897 (expected: 192.168.1.171:61897) -> local 192.168.1.118:31201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] New egress (direct) SSRC for: 192.168.1.171:4029 SSRC: 25181792
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [rtcp] Calling handler for RTCP packet type 201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Forward to sink endpoint: local 192.168.1.118:39679 -> remote 192.168.1.171:4029
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Kernelizing media stream: remote 192.168.1.171:61897 -> local 192.168.1.118:31201
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Kernelizing media stream: 192.168.1.171:61897 -> 192.168.1.118:31201 | 192.168.1.118:39679 -> 192.168.1.171:4029
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31200]: [core] Confirmed peer address as 192.168.1.171:61896
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31200]: [core] Handling packet: remote 192.168.1.171:61896 (expected: 192.168.1.171:61896) -> local 192.168.1.118:31200 (RTP seq 5420 TS 925100 SSRC 25181792)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39678]: [core] Forward to sink endpoint: local 192.168.1.118:39678 -> remote 192.168.1.171:4028 (RTP seq 5420 TS 925100 SSRC 25181792)
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31200]: [core] Kernelizing media stream: remote 192.168.1.171:61896 -> local 192.168.1.118:31200
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31200]: [core] Kernelizing media stream: 192.168.1.171:61896 -> 192.168.1.118:31200 | 192.168.1.118:39678 -> 192.168.1.171:4028
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31200]: [core] Kernelizing media stream: 192.168.1.171:61896 -> 192.168.1.118:31200 | 192.168.1.118:39679 -> 192.168.1.171:4029
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Confirmed peer address as 192.168.1.171:4029
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Handling packet: remote 192.168.1.171:4029 (expected: 192.168.1.171:4029) -> local 192.168.1.118:39679
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 200
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] SR from 4bd14564: RTP TS 2112 PC 13 OC 2080 NTP TS 3981779721/1135242319=3981779721.264319
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5431 J 0 LSR 0 DLSR 0
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] No payload type known for RTCP RR, discarding
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Forward to sink endpoint: local 192.168.1.118:31201 -> remote 192.168.1.171:61897
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] New egress (direct) SSRC for: :0 SSRC: 4bd14564
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 200
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] SR from 4bd14564: RTP TS 2112 PC 13 OC 2080 NTP TS 3981779721/1135242319=3981779721.264319
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5431 J 0 LSR 0 DLSR 0
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] No payload type known for RTCP RR, discarding
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Kernelizing media stream: remote 192.168.1.171:4029 -> local 192.168.1.118:39679
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Kernelizing media stream: 192.168.1.171:4029 -> 192.168.1.118:39679 | 192.168.1.118:31201 -> 192.168.1.171:61897
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] Final packet stats:
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --- Tag 'c63d0c13', created 0:32 ago for branch ''
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscribed to media with monologue tag 'b887636cc21643e881856b933cc4e958' (index: 1)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscription for media with monologue tag 'b887636cc21643e881856b933cc4e958' (index: 1)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ------ Media #1 (audio over RTP/AVP) using PCMU/8000
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:38558 <>   192.168.1.171:27056, SSRC e1fa89f1, in 104 p, 17888 b, 0 e, 30 ts, out 103 p, 17716 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:38559 <>   192.168.1.171:27057 (RTCP), SSRC e1fa89f1, in 3 p, 452 b, 0 e, 30 ts, out 5 p, 356 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --- Tag 'b887636cc21643e881856b933cc4e958', created 0:32 ago for branch ''
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscribed to media with monologue tag 'c63d0c13' (index: 1)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscription for media with monologue tag 'c63d0c13' (index: 1)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscription for media with monologue tag 'b887636cc21643e881856b933cc4e958' (index: 2)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ------ Media #1 (audio over RTP/AVP) using PCMU/8000
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:38204 <>   192.168.1.171:4026 , SSRC 66cb0e3a, in 104 p, 17888 b, 0 e, 30 ts, out 104 p, 17888 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:38205 <>   192.168.1.171:4027  (RTCP), SSRC 66cb0e3a, in 7 p, 512 b, 0 e, 30 ts, out 2 p, 292 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ---     subscribed to media with monologue tag 'b887636cc21643e881856b933cc4e958' (index: 1)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] ------ Media #2 (audio over RTP/AVP) using unknown codec
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:36480 <>                :0    , SSRC 0, in 0 p, 0 b, 0 e, 32 ts, out 0 p, 0 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] --------- Port   192.168.1.118:36481 <>                :0     (RTCP), SSRC 0, in 0 p, 0 b, 0 e, 32 ts, out 0 p, 0 b, 0 e
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] Removing media stream from kernel: local 192.168.1.118:38204 (final call cleanup)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] Removing media stream from kernel: local 192.168.1.118:38205 (final call cleanup)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] Removing media stream from kernel: local 192.168.1.118:38558 (final call cleanup)
INFO: [ZmQ5ZjNiMGU5NzgyMTM3MDUxYmRjMmM1ZmVmYTFhN2I.]: [core] Removing media stream from kernel: local 192.168.1.118:38559 (final call cleanup)
DEBUG: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [codec] Shutting down codec handler for PCMA/8000/1
DEBUG: [codec] Shutting down codec handler for G729/8000/1
DEBUG: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [codec] Shutting down codec handler for PCMU/8000/1
DEBUG: [codec] Shutting down codec handler for telephone-event/8000/1
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] Handling packet: remote 192.168.1.171:61897 (expected: 192.168.1.171:61897) -> local 192.168.1.118:31201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [rtcp] Calling handler for RTCP packet type 200
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [core] SR from 25181792: RTP TS 947180 PC 139 OC 23908 NTP TS 3981750924/227633267=3981750924.053000
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./f975ad3e/1 port 31201]: [rtcp] Calling handler for RTCP packet type 203
INFO: [<null>]: [control] Received command 'unsubscribe' from 127.0.0.1:49085
DEBUG: [<null>]: [control] Dump for 'unsubscribe' from 127.0.0.1:49085: { "supports": [ "load limit" ], "received-from": [ "IP4", "192.168.1.171" ], "call-id": "<null>", "to-tag": "<null>", "command": "unsubscribe", "sip-message-type": "sip_request" }
WARNING: [<null>]: [control] Protocol error in packet from 127.0.0.1:49085: Unknown call-ID [d8:supportsl10:load limite13:received-froml3:IP413:192.168.1.171e7:call-id6:<null>6:to-tag6:<null>7:command11:unsubscribe16:sip-message-type11:sip_requeste]
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Received command 'delete' from 127.0.0.1:49085
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Dump for 'delete' from 127.0.0.1:49085: { "supports": [ "load limit" ], "received-from": [ "IP4", "192.168.1.171" ], "call-id": "MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.", "from-tag": "f975ad3e", "command": "delete", "sip-message-type": "sip_request" }
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Scheduling deletion of call branch 'f975ad3e' (via-branch '') in 30 seconds
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Scheduling deletion of call branch '62d8c687a95247debaae81d06cf02a7b' (via-branch '') in 30 seconds
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [core] Scheduling deletion of entire call in 30 seconds
INFO: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Replying to 'delete' from 127.0.0.1:49085 (elapsed time 0.000852 sec)
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: [control] Response dump for 'delete' to 127.0.0.1:49085: { "created": 1772762128, "created_us": 321706, "created_ts": 1772762128321706, "last signal": 1772762128, "last redis update": 0, "SSRC": { "622335890": { "bytes": 172, "packets": 1, "last RTP timestamp": 925100, "last RTP seq": 5558 }, "1272005988": { "bytes": 172, "packets": 1, "last RTP timestamp": 160, "last RTP seq": 11609 } }, "tags": { "f975ad3e": { "tag": "f975ad3e", "created": 1772762128, "subscriptions": [ { "tag": "62d8c687a9524 ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 7debaae81d06cf02a7b", "type": "offer/answer" } ], "subscribers": [ { "tag": "62d8c687a95247debaae81d06cf02a7b", "type": "pub/sub" } ], "medias": [ { "index": 1, "type": "audio", "protocol": "RTP/AVP", "codec": "PCMU/8000", "streams": [ { "local port": 31200, "local address": "192.168.1.118", "family": "IPv4", "endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 61896 }, "advertised endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 61896 }, "last packet": 177276213 ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 1, "last kernel packet": 1772762131, "last user packet": 1772762128, "SSRC": 622335890, "flags": [ "RTP", "filled", "confirmed", "kernelized" ], "stats": { "packets": 139, "bytes": 23908, "errors": 0 }, "stats_out": { "packets": 142, "bytes": 24424, "errors": 0 } }, { "local port": 31201, "local address": "192.168.1.118", "family": "IPv4", "endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 61897 }, "advertised endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 61 ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... 897 }, "last packet": 1772762131, "last kernel packet": 1772762131, "last user packet": 1772762131, "SSRC": 622335890, "flags": [ "RTCP", "filled", "confirmed", "kernelized" ], "stats": { "packets": 3, "bytes": 452, "errors": 0 }, "stats_out": { "packets": 3, "bytes": 200, "errors": 0 } } ], "flags": [ "initialized", "send", "recv" ], "ingress SSRCs": [ { "SSRC": 622335890, "bytes": 172, "packets": 1, "last RTP timestamp": 925100, "last RTP seq": 5558 } ], "egress SSRCs": [ { "SSRC": 1272005988, ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ...  "bytes": 22720, "packets": 142, "last RTP timestamp": 22880, "last RTP seq": 11609 } ] } ], "VSC": [  ] }, "62d8c687a95247debaae81d06cf02a7b": { "tag": "62d8c687a95247debaae81d06cf02a7b", "created": 1772762128, "subscriptions": [ { "tag": "f975ad3e", "type": "pub/sub" }, { "tag": "62d8c687a95247debaae81d06cf02a7b", "type": "pub/sub" } ], "subscribers": [ { "tag": "f975ad3e", "type": "offer/answer" }, { "tag": "62d8c687a95247debaae81d06cf02a7b", "type": "pub/sub" } ], "medias": [ { "index": 1, " ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... type": "audio", "protocol": "RTP/AVP", "codec": "PCMU/8000", "streams": [ { "local port": 39678, "local address": "192.168.1.118", "family": "IPv4", "endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 4028 }, "advertised endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 4028 }, "last packet": 1772762131, "last kernel packet": 1772762131, "last user packet": 1772762128, "SSRC": 1272005988, "flags": [ "RTP", "filled", "confirmed", "kernelized" ], "stats": { "packet ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... s": 143, "bytes": 24596, "errors": 0 }, "stats_out": { "packets": 139, "bytes": 23908, "errors": 0 } }, { "local port": 39679, "local address": "192.168.1.118", "family": "IPv4", "endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 4029 }, "advertised endpoint": { "family": "IPv4", "address": "192.168.1.171", "port": 4029 }, "last packet": 1772762128, "last kernel packet": 0, "last user packet": 1772762128, "SSRC": 1272005988, "flags": [ "RTCP", "filled", "confirmed", "kernelized" ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ...  ], "stats": { "packets": 3, "bytes": 200, "errors": 0 }, "stats_out": { "packets": 2, "bytes": 292, "errors": 0 } } ], "flags": [ "initialized", "send", "ICE controlling" ], "ingress SSRCs": [ { "SSRC": 1272005988, "bytes": 172, "packets": 1, "last RTP timestamp": 160, "last RTP seq": 11609 } ], "egress SSRCs": [ { "SSRC": 622335890, "bytes": 22252, "packets": 139, "last RTP timestamp": 947180, "last RTP seq": 5558 } ] }, { "index": 2, "type": "audio", "protocol": "RTP/AVP", "streams": [ { "loc ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ... al port": 39538, "local address": "192.168.1.118", "family": "IPv4", "endpoint": {  }, "advertised endpoint": {  }, "last packet": 1772762128, "last kernel packet": 0, "last user packet": 1772762128, "flags": [ "RTP" ], "stats": { "packets": 0, "bytes": 0, "errors": 0 }, "stats_out": { "packets": 0, "bytes": 0, "errors": 0 } }, { "local port": 39539, "local address": "192.168.1.118", "family": "IPv4", "endpoint": {  }, "advertised endpoint": {  }, "last packet": 1772762128, "last kernel packet": ...
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc.]: ...  0, "last user packet": 1772762128, "flags": [ "RTCP" ], "stats": { "packets": 0, "bytes": 0, "errors": 0 }, "stats_out": { "packets": 0, "bytes": 0, "errors": 0 } } ], "flags": [ "ICE controlling" ], "ingress SSRCs": [  ], "egress SSRCs": [ { "SSRC": 1272005988, "bytes": 0, "packets": 0, "last RTP timestamp": 0, "last RTP seq": 0 } ] } ], "VSC": [  ] } }, "totals": { "RTP": { "packets": 282, "bytes": 48504, "errors": 0 }, "RTCP": { "packets": 6, "bytes": 652, "errors": 0 } }, "result": "ok" }
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Handling packet: remote 192.168.1.171:4029 (expected: 192.168.1.171:4029) -> local 192.168.1.118:39679
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 200
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] SR from 4bd14564: RTP TS 23024 PC 143 OC 22880 NTP TS 3981779723/3773096986=3981779723.878493
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5558 J 49 LSR 2995522961 DLSR 4175
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated round-trip time for 25181792 is 353 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated jitter for 25181792 is 6 ms
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Adding opposide side RTT of 0 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 203
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 200
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] SR from 4bd14564: RTP TS 23024 PC 143 OC 22880 NTP TS 3981779723/3773096986=3981779723.878493
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5558 J 49 LSR 2995522961 DLSR 4175
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated round-trip time for 25181792 is 353 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated jitter for 25181792 is 6 ms
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Adding opposide side RTT of 0 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 203
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Handling packet: remote 192.168.1.171:4029 (expected: 192.168.1.171:4029) -> local 192.168.1.118:39679
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5558 J 49 LSR 2995522961 DLSR 4180
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated round-trip time for 25181792 is 277 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated jitter for 25181792 is 6 ms
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Adding opposide side RTT of 0 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 203
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 201
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] RR from 4bd14564 about 25181792: FL 0 TL 0 HSR 5558 J 49 LSR 2995522961 DLSR 4180
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated round-trip time for 25181792 is 277 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Calculated jitter for 25181792 is 6 ms
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [core] Adding opposide side RTT of 0 us
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 202
DEBUG: [MTEzODBhODY2NjA0ZWQ5NzQ3OTMwMTI1ZmM5OTk5NDc./62d8c687a95247debaae81d06cf02a7b/1 port 39679]: [rtcp] Calling handler for RTCP packet type 203
`

```

xiaofang han (hance)

unread,
Mar 6, 2026, 5:20:41 AM (9 days ago) Mar 6
to Sipwise rtpengine



Issue reproducible.

Richard Fuchs

unread,
Mar 6, 2026, 6:56:55 AM (9 days ago) Mar 6
to rtpe...@googlegroups.com
On 05/03/2026 22.14, xiaofang han (hance) wrote:
> Failed to call kamailio's rtpengine_subscribe_answer
>
> The following information is in rtpengine.log:
> Protocol error in packet from 127.0.0.1:58489: Failed to process
> subscription answer

Your tag usage is wrong. The call itself is set up between from-tag
"f975ad3e" and to-tag "62d8c687a95247debaae81d06cf02a7b".

Then the subscribe-request comes in and it also uses "to-tag":
"62d8c687a95247debaae81d06cf02a7b". This is incorrect.

The subscription requires a unique, distinct to-tag to identify it.
Either supply your own, or omit the to-tag completely and one will be
generated and then returned in the subscribe-request result.

Cheers

Reply all
Reply to author
Forward
0 new messages