search

sipcapture / captagent

100% Open-Source Packet Capture Agent for HEP
https://sipcapture.org
GNU Affero General Public License v3.0
168 stars 75 forks source link

RTCP Error - "protocol_sip.c:133 couldn't parse RTCP to json" #155

Closed arainero closed 6 years ago

arainero commented 7 years ago

Hello, It was great meeting some of you at Astricon. I have just recently installed Homer via docer and have captagent installed on one of my Asterisk 11 servers. SIP with SDP appears to be working fine with Homer.

However, I can not get any RTP statistics to work. I see "[ERR] protocol_sip.c:133 couldn't parse RTCP to json" in the captagent output which I suspect is the issue. Any debug tips or info to provide to help resolve this would be appreciated.

I'm using the latest GIT version.

Here is the RTPC section of captagent with level 10 logging:


IS RTCP

[DEBUG] database_hash.c:165 IP PORT: 192.168.128.3:8001
[DEBUG] database_hash.c:280 IP PORT: [192.168.128.3:800                                                       1]
[DEBUG] database_hash.c:175 SESSION ID: MDVlZjdiZDk2ZGZ                                                       lODEyNDgyOGRiNzMyOTk2M2Y1ZWU.
[DEBUG] parser_rtcp.c:83 Parsing compound packet (total                                                        of 88 bytes)

[DEBUG] parser_rtcp.c:89 #1 SR (200)

[DEBUG] parser_rtcp.c:136 #2 SDES (202)

[DEBUG] parser_rtcp.c:185 #3 BYE (203)

[DEBUG] protocol_rtcp.c:98 GOODBYE or APP MESSAGE. Ignore!

[ERR] protocol_sip.c:133 couldn't parse RTCP to json

captanget.xml:

<document type="captagent/xml">
        <configuration name="core.conf" description="CORE Settings" serial="2014024212">
            <settings>
                <param name="debug" value="10"/>
                <param name="version" value="2"/>
                <param name="serial" value="2014056501"/>
                <param name="uuid" value="00781a4a-5b69-11e4-9522-bb79a8fcf0f3"/>
                <param name="daemon" value="false"/>
                <param name="syslog" value="false"/>
                <param name="pid_file" value="/var/run/captagent.pid"/>
                <!-- Configure using installation path if different from default -->
                <param name="module_path" value="/usr/local/captagent/lib/captagent/modules"/>
                <param name="config_path" value="/usr/local/captagent/etc/captagent/"/>
                <param name="capture_plans_path" value="/usr/local/captagent/etc/captagent/captureplans"/>
                <param name="backup" value="/usr/local/captagent/etc/captagent/backup"/>
                <param name="chroot" value="/usr/local/captagent/etc/captagent"/>
            </settings>
        </configuration>
        <configuration name="modules.conf" description="Modules">
            <modules>

                <load module="transport_hep" register="local"/>
                <load module="protocol_sip" register="local"/>
                <load module="database_hash" register="local"/>
                <load module="protocol_rtcp" register="local"/>
                <load module="socket_pcap" register="local"/>
                <load module="socket_raw" register="local"/>
        <!--
                <load module="socket_rtcpxr" register="local"/>
                <load module="socket_tzsp" register="local"/>
                <load module="protocol_ss7" register="local"/>
                <load module="protocol_tcp" register="local"/>
                <load module="transport_json" register="local"/>
                <load module="protocol_rtcp" register="local"/>
                <load module="interface_http" register="local"/>
                <load module="database_redis" register="local"/>
                <load module="socket_pfring" register="local"/>
            -->
            </modules>
        </configuration>
</document>

protocol_rtcp.xml:

<document type="captagent_module/xml">
    <module name="protocol_rtcp" description="RTCP Protocol" serial="2014010402">
        <profile name="proto_rtcp" description="PROTO RTCP" enable="true" serial="2014010402">
            <settings>
                <param name="rtcp-version" value="2"/>
            </settings>
        </profile>
    </module>
</document>

socket_pcap.xml:

<?xml version="1.0"?>
<document type="captagent_module/xml">
    <module name="socket_pcap" description="HEP Socket" serial="2014010402">
        <profile name="socketspcap_sip" description="HEP Socket" enable="true" serial="2014010402">
            <settings>
                <param name="dev" value="any"/>
                <param name="promisc" value="true"/>
                <param name="reasm" value="false"/>
                <param name="tcpdefrag" value="false"/>
                <param name="capture-plan" value="sip_capture_plan.cfg"/>
                <param name="filter">
                    <value>portrange 5060-5091</value>
                </param>
            </settings>
        </profile>
        <profile name="socketspcap_rtcp" description="RTCP Socket" enable="true" serial="2014010402">
            <settings>
                <param name="dev" value="any"/>
                <param name="promisc" value="true"/>
                <param name="reasm" value="false"/>
                <!-- size in MB -->
                <param name="ring-buffer" value="20"/>
                <!-- for rtp && rtcp < 250 -->
                <param name="snap-len" value="256"/>
                <param name="capture-filter" value="rtcp"/>
                <param name="capture-plan" value="rtcp_capture_plan.cfg"/>
                <param name="filter">
                    <value>portrange 10000-50000 and len >=50 </value>
                </param>
            </settings>
        </profile>
    </module>
</document>

rtcp_capture_plan.cfg:

capture[pcap] {

        # here we can check source/destination IP/port, message size
        if(msg_check("size", "30")) {

                if(is_rtcp()) {
                        #Only for redis!

                        if(is_rtcp_exist()) {

                                #Convert to JSON if needed.
                                if(parse_rtcp_to_json()) {

                                        #Can be defined many profiles in transport_hep.xml
                                        if(!send_hep("hepsocket")) {
                                                clog("ERROR", "Error sending HEP!!!!");
                                        }

                                } else {
                                        clog("ERROR", "couldn't parse RTCP to json");
                                }

                        } else {
                                clog("ERROR", "Couldnot find this call");
                        }
                } else {
                        clog("ERROR", "This is not RTCP");
                }
        }
        drop;
}

sip_capture_plan.cfg:

capture[pcap] {

        # here we can check source/destination IP/port, message size
        if(msg_check("size", "100")) {

            #Do parsing
            if(parse_sip()) {
                #Can be defined many profiles in transport_hep.xml

                if(!send_hep("hepsocket")) {
                    clog("ERROR", "Error sending HEP!!!!");
                }

                 if(sip_has_sdp())
                 {
                        #Activate it for RTCP checks
                        if(!check_rtcp_ipport())
                        {
                                clog("ERROR", "ALREADY EXIST");
                        }
                 }

                #Duplicate all INVITEs to JSON transport
                # if(sip_is_method() && sip_check("method","INVITE")) {
                #    #Can be defined many profiles in transport_json.xml
                #    if(!send_json("jsonsocket")) {
                #       clog("ERROR", "Error sending JSON!!!");
                #    }
                # }
            }
        }
        drop;
}

transport_hep.xml:

<?xml version="1.0"?>
<document type="captagent_module/xml">
    <module name="transport_hep" description="HEP Protocol" serial="2014010402">
        <profile name="hepsocket" description="Transport HEP" enable="true" serial="2014010402">
            <settings>
                <param name="version" value="3"/>
                <param name="capture-host" value="X.X.X.196"/>
                <param name="capture-port" value="9060"/>
                <param name="capture-proto" value="udp"/>
                <param name="capture-id" value="2001"/>
                <param name="capture-password" value=""/>
                <param name="payload-compression" value="false"/>
            </settings>
        </profile>
    </module>
</document>

transport_json.xml:

<?xml version="1.0"?>
<document type="captagent_module/xml">
    <module name="transport_json" description="JSON Protocol" serial="2014010402">
        <profile name="jsonsocket" description="Transport JSON" enable="true" serial="2014010402">
            <settings>
                <param name="capture-version" value="1"/>
                <param name="capture-host" value="X.X.X.196"/>
                <param name="capture-port" value="9060"/>
                <param name="capture-proto" value="udp"/>
                <param name="capture-id" value="2001"/>
                <param name="payload-send" value="true"/>
            </settings>
        </profile>
    </module>
</document>
kYroL01 commented 7 years ago

Hi @arainero . Can u please share us a pcap with this RTCP traffic to see the issue ? Thanks

btriller commented 7 years ago

if the compound RTCP packet has an APP or a BYE packet at the end, the whole packet is dropped. src/modules/protocol/rtcp/protocol_rtcp.c#L91-L106 src/modules/protocol/rtcp/parser_rtcp.c#L184-L195

btriller commented 7 years ago

it's a bug, I'd say :)

kYroL01 commented 7 years ago

I'm agree with @btriller . I didn't notice that on a fast look. Thanks.

kYroL01 commented 7 years ago

@btriller I wanted add the other label, sorry :)

arainero commented 7 years ago

@kYroL01 Would you still like me to get a pcap or is it clear enough without one?

kYroL01 commented 7 years ago

If u still pass me the pcap could be easy for test, because I don't have RTCP pcap with APP or BYE pkt. Thank you

arainero commented 7 years ago

@kYroL01 I sent you an email with the requested files.

kYroL01 commented 7 years ago

@arainero Can u check now after this ? Thanks https://github.com/sipcapture/captagent/commit/2122366d90bf06ca7253212fc89cd36a846fb652

arainero commented 7 years ago

I recompiled with those changes. I don't see any json errors like I had before. However, I don't see any information in QoS reports though. No graphs or anything.

kYroL01 commented 7 years ago

IMHO the problems could be two:

  1. No data is sent to the application;
  2. The created buffer, when RTCP is parsed, is empty.
arainero commented 7 years ago

Is there anything you recommend for me to do or try so RTP stats will work?

arainero commented 7 years ago

Here is a captagent debug level 10 dump. It appears that there is JSON RTCP, but it doesn't get to the Homer server for some reason. All other SIP info is there though.

Just to make sure I understand this properly. The RTCP statistics will populate under "QoS Reports" on the right side, correct?

[DEBUG] modules.c:50 Loading module: [transport_hep]
[NOTICE] Loaded transport_hep
[DEBUG] modules.c:50 Loading module: [protocol_sip]
[NOTICE] Loaded protocol_sip
[DEBUG] modules.c:50 Loading module: [database_hash]
[NOTICE] Loaded database_hash
[DEBUG] modules.c:50 Loading module: [protocol_rtcp]
[NOTICE] Loaded protocol_rtcp
[DEBUG] modules.c:50 Loading module: [socket_pcap]
[NOTICE] Loaded socket_pcap
[DEBUG] socket_pcap.c:573 Activating device: any

[DEBUG] socket_pcap.c:609 Activated device: [any]

[NOTICE] Using filter: (portrange 5060-5091)
[DEBUG] conf_function.c:453 find_export_record: found <msg_check> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <parse_sip> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <send_hep> in module transport_hep [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <sip_has_sdp> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <check_rtcp_ipport> in module database_hash [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] socket_pcap.c:573 Activating device: any

[DEBUG] socket_pcap.c:764 Link offset interface type [113] [113] [16]
[DEBUG] socket_pcap.c:609 Activated device: [any]

[NOTICE] Using filter: (portrange 5060-50000 and len >=50 ) and (ip and ip[6] & 0x2 = 0 and ip[6:2] & 0x1fff = 0 and udp and udp[8] & 0xc0 = 0x80 and udp[9] >= 0xc8 && udp[9] <= 0xcc)
[DEBUG] conf_function.c:453 find_export_record: found <msg_check> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <is_rtcp> in module protocol_rtcp [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <is_rtcp_exist> in module database_hash [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <parse_rtcp_to_json> in module protocol_rtcp [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <send_hep> in module transport_hep [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] conf_function.c:453 find_export_record: found <clog> in module protocol_sip [/usr/local/captagent/lib/captagent/modules]
[DEBUG] modules.c:50 Loading module: [socket_raw]
[DEBUG] socket_pcap.c:764 Link offset interface type [113] [113] [16]
[NOTICE] Loaded socket_raw
[DEBUG] captagent.c:316 The Captagent is ready
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [INVITE sip:alonzotest2@10.10.10.42;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-
Max-Forwards: 70
Contact: <sip:alonzotest@192.168.128.17:5060;transport=UDP>
To: <sip:alonzotest2@10.10.10.42;transport=UDP>
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Content-Type: application/sdp
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Zoiper for Windows 2.43 r24984
Allow-Events: presence, kpml
Content-Length: 331

v=0
o=Zoiper_user 0 0 IN IP4 192.168.128.17
s=Zoiper_session
c=IN IP4 192.168.128.17
t=0 0
m=audio 8000 RTP/AVP 3 0 8 110 98 101
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:110 speex/8000
a=rtpmap:98 iLBC/8000
a=fmtp:98 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@10.10.10.42;transport=UDP

[DEBUG] database_hash.c:140 RTCP CALLID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
[DEBUG] database_hash.c:141 RTCP IP PORT: 192.168.128.17:8001
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 600;refresher=uas
Contact: <sip:alonzotest2@10.10.10.42:5060>
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [INVITE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 11.14.1
Date: Thu, 12 Oct 2017 12:31:26 GMT
Session-Expires: 600
Min-SE: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 234

v=0
o=root 1895147476 1895147476 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 13228 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

[DEBUG] database_hash.c:140 RTCP CALLID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:13229
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 600;refresher=uas
Contact: <sip:alonzotest2@10.10.10.42:5060>
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [INVITE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 11.14.1
Date: Thu, 12 Oct 2017 12:31:26 GMT
Session-Expires: 600
Min-SE: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 234

v=0
o=root 1895147476 1895147476 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 13228 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

[DEBUG] database_hash.c:140 RTCP CALLID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:13229
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport=5060
Contact: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: X-Lite release 5.0.1 stamp 86895
Allow-Events: talk, hold
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 600;refresher=uas
Contact: <sip:alonzotest2@10.10.10.42:5060>
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK57972fab;rport=5060
Contact: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS
Content-Type: application/sdp
Supported: replaces
User-Agent: X-Lite release 5.0.1 stamp 86895
Content-Length: 204

v=0
o=- 11166860 3 IN IP4 192.168.128.17
s=X-Lite release 5.0.1 stamp 86895
c=IN IP4 192.168.128.17
t=0 0
m=audio 60216 RTP/AVP 0 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
]

[DEBUG] database_hash.c:140 RTCP CALLID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
[DEBUG] database_hash.c:141 RTCP IP PORT: 192.168.128.17:60217
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [ACK sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK5bb63163;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 102 ACK
User-Agent: Asterisk PBX 11.14.1
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 600;refresher=uas
Contact: <sip:alonzotest2@10.10.10.42:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 234

v=0
o=root 1060127621 1060127621 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 11534 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] database_hash.c:140 RTCP CALLID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:11535
IS RTCP

[DEBUG] database_hash.c:165 IP PORT: 192.168.128.17:60217
[DEBUG] database_hash.c:280 IP PORT: [192.168.128.17:60217]
[DEBUG] database_hash.c:175 SESSION ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
[DEBUG] parser_rtcp.c:77 Parsing compound packet (total of 72 bytes)

[DEBUG] parser_rtcp.c:88 #1 SR (200)

[DEBUG] parser_rtcp.c:136 #2 SDES (202)

[DEBUG] protocol_rtcp.c:121 JSON RTCP { "sender_information":{"ntp_timestamp_sec":551520733,"ntp_timestamp_usec":2727918681,"octets":800,"rtp_timestamp":1555201044, "packets":5},"sdes_ssrc":1384644827,"sdes_chunk_ssrc":17850959,"sdes_information": [ {"type":0,"text":"Rˆ"},{"type":98,"text":"rQbQ164Rl5AT5V"}],"sdes_report_count":2}

[DEBUG] transport_hep.c:273 LETS FREE IT!
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-92c9ce87166a55b6-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 INVITE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 600;refresher=uas
Contact: <sip:alonzotest2@10.10.10.42:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 234

v=0
o=root 1060127621 1060127621 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 11534 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] database_hash.c:140 RTCP CALLID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:11535
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [ACK sip:alonzotest2@10.10.10.42:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-63546a96fe0a7bbf-1---d8754z-
Max-Forwards: 70
Contact: <sip:alonzotest@192.168.128.17:5060;transport=UDP>
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 ACK
User-Agent: Zoiper for Windows 2.43 r24984
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@10.10.10.42:5060

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [ACK sip:alonzotest2@10.10.10.42:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-63546a96fe0a7bbf-1---d8754z-
Max-Forwards: 70
Contact: <sip:alonzotest@192.168.128.17:5060;transport=UDP>
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 1 ACK
User-Agent: Zoiper for Windows 2.43 r24984
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@10.10.10.42:5060

IS RTCP

[DEBUG] database_hash.c:165 IP PORT: 192.168.128.17:8001
[DEBUG] database_hash.c:280 IP PORT: [192.168.128.17:8001]
[DEBUG] database_hash.c:175 SESSION ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
[DEBUG] parser_rtcp.c:77 Parsing compound packet (total of 104 bytes)

[DEBUG] parser_rtcp.c:88 #1 SR (200)

[DEBUG] parser_rtcp.c:136 #2 SDES (202)

[DEBUG] protocol_rtcp.c:121 JSON RTCP { "sender_information":{"ntp_timestamp_sec":601852381,"ntp_timestamp_usec":310624490,"octets":27680,"rtp_timestamp":302749106, "packets":173},"ssrc":1158380256,"type":200, "report_blocks":[{"source_ssrc":1384644827,"highest_seq_no":21511,"fraction_lost":0,"ia_jitter":0,"packets_lost":0,"lsr":0,"dlsr":0}],"report_count":1,"sdes_ssrc":1158380256,"sdes_chunk_ssrc":18243954,"sdes_information": [ {"type":0,"text":"Ezàar"},{"type":97,"text":"nero@RAFAEL-PC"}],"sdes_report_count":2}

[DEBUG] transport_hep.c:273 LETS FREE IT!
IS RTCP

[DEBUG] database_hash.c:165 IP PORT: 192.168.128.17:8001
[DEBUG] database_hash.c:280 IP PORT: [192.168.128.17:8001]
[DEBUG] database_hash.c:175 SESSION ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
[DEBUG] parser_rtcp.c:77 Parsing compound packet (total of 88 bytes)

[DEBUG] parser_rtcp.c:88 #1 SR (200)

[DEBUG] parser_rtcp.c:136 #2 SDES (202)

[DEBUG] parser_rtcp.c:186 #3 BYE (203)

[DEBUG] protocol_rtcp.c:121 JSON RTCP { "sender_information":{"ntp_timestamp_sec":618629597,"ntp_timestamp_usec":3746532695,"octets":30240,"rtp_timestamp":1377342898, "packets":189},"sdes_ssrc":1158380256,"sdes_chunk_ssrc":18243954,"sdes_information": [ {"type":0,"text":"Ezàar"},{"type":97,"text":"nero@RAFAEL-PC"}],"sdes_report_count":2}

[DEBUG] transport_hep.c:273 LETS FREE IT!
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [BYE sip:alonzotest2@10.10.10.42:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-6e0f508550921405-1---d8754z-
Max-Forwards: 70
Contact: <sip:alonzotest@192.168.128.17:5060;transport=UDP>
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 2 BYE
User-Agent: Zoiper for Windows 2.43 r24984
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@10.10.10.42:5060

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.17:5060;branch=z9hG4bK-d8754z-6e0f508550921405-1---d8754z-;received=192.168.128.17;rport=5060
From: <sip:alonzotest@10.10.10.42;transport=UDP>;tag=8e307026
To: <sip:alonzotest2@10.10.10.42;transport=UDP>;tag=as2b179bb3
Call-ID: M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.
CSeq: 2 BYE
Server: Asterisk PBX 11.14.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [INVITE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 11.14.1
Date: Thu, 12 Oct 2017 12:31:32 GMT
Session-Expires: 600
Min-SE: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 234

v=0
o=root 1665473441 1665473441 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 17488 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

[DEBUG] database_hash.c:140 RTCP CALLID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:17489
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [BYE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK6b392af8;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 103 BYE
User-Agent: Asterisk PBX 11.14.1
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [INVITE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 11.14.1
Date: Thu, 12 Oct 2017 12:31:32 GMT
Session-Expires: 600
Min-SE: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 234

v=0
o=root 1665473441 1665473441 IN IP4 10.10.10.42
s=Asterisk PBX 11.14.1
c=IN IP4 10.10.10.42
t=0 0
m=audio 17488 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

[DEBUG] database_hash.c:140 RTCP CALLID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
[DEBUG] database_hash.c:141 RTCP IP PORT: 10.10.10.42:17489
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [BYE sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK6b392af8;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 103 BYE
User-Agent: Asterisk PBX 11.14.1
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

IS RTCP

[DEBUG] database_hash.c:165 IP PORT: 192.168.128.17:60217
[DEBUG] database_hash.c:280 IP PORT: [192.168.128.17:60217]
[DEBUG] database_hash.c:175 SESSION ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
[DEBUG] parser_rtcp.c:77 Parsing compound packet (total of 56 bytes)

[DEBUG] parser_rtcp.c:115 #1 RR (201)

[DEBUG] parser_rtcp.c:186 #2 BYE (203)

[DEBUG] protocol_rtcp.c:121 JSON RTCP { "ssrc":1384644827,"type":201, "report_blocks":[{"source_ssrc":1158380256,"highest_seq_no":4788,"fraction_lost":0,"ia_jitter":48,"packets_lost":0,"lsr":0,"dlsr":0}],"report_count":1}

[DEBUG] transport_hep.c:273 LETS FREE IT!
PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 INVITE
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK6b392af8;rport=5060
Contact: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 103 BYE
User-Agent: X-Lite release 5.0.1 stamp 86895
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [CANCEL sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 CANCEL
User-Agent: Asterisk PBX 11.14.1
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 INVITE
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK6b392af8;rport=5060
Contact: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6253a002
From: <sip:5555555555@10.10.10.42>;tag=as22219ca9
Call-ID: 1921d7340c3819d4540bbb226b8d3523@10.10.10.42:5060
CSeq: 103 BYE
User-Agent: X-Lite release 5.0.1 stamp 86895
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [CANCEL sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 CANCEL
User-Agent: Asterisk PBX 11.14.1
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport=5060
Contact: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6d44a72d
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 CANCEL
User-Agent: X-Lite release 5.0.1 stamp 86895
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6d44a72d
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 INVITE
User-Agent: X-Lite release 5.0.1 stamp 86895
Content-Length: 0

]

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [ACK sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport
Max-Forwards: 70
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=6d44a72d
Contact: <sip:5555555555@10.10.10.42:5060>
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 ACK
User-Agent: Asterisk PBX 11.14.1
Content-Length: 0

]

[DEBUG] parser_sip.c:792 INVITE RURI: sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4

PARSE SIP

[DEBUG] protocol_sip.c:370 SIP: [SIP/2.0 481 Call/Transaction Does Not Exist
Via: SIP/2.0/UDP 10.10.10.42:5060;branch=z9hG4bK50378ada;rport=5060
To: <sip:alonzotest2@192.168.128.17:53035;rinstance=d9c6ccb542d575e4>;tag=31965d69
From: <sip:5555555555@10.10.10.42>;tag=as512dc129
Call-ID: 7c60594138c13b2c723fd3d626b6ad09@10.10.10.42:5060
CSeq: 102 CANCEL
Content-Length: 0

]

[DEBUG] captagent.c:87 The agent has been terminated
[NOTICE] unloaded module socket_raw
[NOTICE] unloaded module socket_pcap
[DEBUG] socket_pcap.c:775 loop stopped by breakloop
------- OUT OF CALLBACK PROTO ------
[DEBUG] socket_pcap.c:796 exit loop
[DEBUG] socket_pcap.c:775 loop stopped by breakloop
------- OUT OF CALLBACK PROTO ------
[DEBUG] socket_pcap.c:796 exit loop
[NOTICE] Unloaded protocol_rtcp
[NOTICE] unloaded module database_hash
[NOTICE] unloaded module protocol_sip
[NOTICE] unloaded module transport_hep
btriller commented 7 years ago

@arainero first, check for data in Homer database. SELECT * FROM rtcp_capture WHERE correlation_id IN ('M2JjZGI2MDYyYmU4N2E2OTdmNDY2NDkwNDQ0MThlMGQ.'). Then, check for errors regarding JSON decoding in php-fpm/Apache/syslog. The broken RTCP SDES items parsing (first SDES item is read from wrong byte) messes up json_decode in homer-api. You can workaround the broken JSON ("sdes_information": [ {"type":0,"text":"E�z�ar"},{"type":97,"text":"nero@RAFAEL-PC"}]) by removing non-printable characters: if ($data[$key]["msg"] == NULL) $data[$key]["msg"] = json_decode(preg_replace( '/[^[:print:]]/', '',$json), true);

arainero commented 7 years ago

@btriller I have homer installed via docker and I don't have too much docker knowledge at the moment. Is there a simple way to access the database container or expose it the outside?

lmangani commented 7 years ago

Depending how you launched the container, you can run docker ps and mark the ID of the homer container, followed by docker exec -ti <CONTAINER_ID> bash to get a shall into the system. From there you can launch mysql and do all the rest as in a regular system (just don't stop services)

arainero commented 7 years ago

Thanks @lmangani.

@btriller I was able to see the RTCP JSON data in the database. So for the statistics to display I have to modify the homer-api file?

I'm slightly confused on the step after checking to see if the data exists in the database.

arainero commented 7 years ago

@btriller

This is what I see appear in the apache error log when opening reports for a call:


[Tue Oct 17 11:26:31.916086 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 35, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.916190 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Alarm.php on line 39, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.916264 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Admin.php on line 38, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.918482 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Search.php on line 1362, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.918538 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Undefined variable: limit in /var/www/html/api/RestApi/Search.php on line 1432, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.921791 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 1445, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922396 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Search.php on line 1187, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922467 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant CLEGTAIL - assumed 'CLEGTAIL' in /var/www/html/api/RestApi/Search.php on line 1225, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922485 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant CLEGTAIL - assumed 'CLEGTAIL' in /var/www/html/api/RestApi/Search.php on line 1232, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922490 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant CLEGTAIL - assumed 'CLEGTAIL' in /var/www/html/api/RestApi/Search.php on line 1248, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922493 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant CLEGTAIL - assumed 'CLEGTAIL' in /var/www/html/api/RestApi/Search.php on line 1251, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.922498 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant CLEGTAIL - assumed 'CLEGTAIL' in /var/www/html/api/RestApi/Search.php on line 1255, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.923301 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 1327, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.924662 2017] [:error] [pid 32] [client 192.168.128.17:52246] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 1327, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.928702 2017] [:error] [pid 31] [client 192.168.128.17:52247] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 35, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.928778 2017] [:error] [pid 31] [client 192.168.128.17:52247] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Alarm.php on line 39, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.928837 2017] [:error] [pid 31] [client 192.168.128.17:52247] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Admin.php on line 38, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.930594 2017] [:error] [pid 31] [client 192.168.128.17:52247] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 1266, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.931795 2017] [:error] [pid 33] [client 192.168.128.17:52248] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 35, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.931866 2017] [:error] [pid 33] [client 192.168.128.17:52248] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Alarm.php on line 39, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.931918 2017] [:error] [pid 33] [client 192.168.128.17:52248] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Admin.php on line 38, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.933592 2017] [:error] [pid 33] [client 192.168.128.17:52248] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 1378, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.934818 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Search.php on line 35, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.934887 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Alarm.php on line 39, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.934939 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Admin.php on line 38, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.937477 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 216, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.941006 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 895, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.946124 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 553, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.946666 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant RTCP_TABLE_PARTITION - assumed 'RTCP_TABLE_PARTITION' in /var/www/html/api/RestApi/Report.php on line 593, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.950235 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 710, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.952591 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Undefined index: node in /var/www/html/api/RestApi/Report.php on line 387, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.954218 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant RTCP_TABLE_PARTITION - assumed 'RTCP_TABLE_PARTITION' in /var/www/html/api/RestApi/Report.php on line 425, referer: http://10.10.10.196/
[Tue Oct 17 11:26:31.954442 2017] [:error] [pid 49] [client 192.168.128.17:52245] PHP Notice:  Use of undefined constant SYSLOG_ENABLE - assumed 'SYSLOG_ENABLE' in /var/www/html/api/RestApi/Report.php on line 437, referer: http://10.10.10.196/
lmangani commented 7 years ago

@arainero you might want to give HEPlify a try see if it simplifies the utilization issues with the full agent.

lmangani commented 6 years ago

@arainero homer-api patched for non-printable chars, not sure if this was your issue but worth a shot - Thanks @btriller https://github.com/sipcapture/homer-api/commit/82188fd9d2944cc3f9534589738ab874b3c7620e