PJSIP : Expected ACK not send

ghost00 · February 18, 2020, 2:32pm

Hi all ,
i try to understand why asterisk not send the expected ACK.

Can you help me ?

Thx a lot.

G

Below
INVITE
100 Trying
180 Ringing
200/OK
200/OK
and not ack…

Frame 1165: 943 bytes on wire (7544 bits), 943 bytes captured (7544 bits)
Ethernet II, Src: Castel_51:0d:44 (00:0e:af:51:0d:44), Dst: Alcatel-_16:2b:4e (2c:fa:a2:16:2b:4e)
Internet Protocol Version 4, Src: 10.21.30.220, Dst: 10.21.31.3
User Datagram Protocol, Src Port: 5060, Dst Port: 5060
Session Initiation Protocol (INVITE)
    Request-Line: INVITE sip:5001@10.21.31.3 SIP/2.0
        Method: INVITE
        Request-URI: sip:5001@10.21.31.3
        [Resent Packet: False]
    Message Header
        Via: SIP/2.0/UDP 10.21.30.220:5060;rport;branch=z9hG4bKPjtxW1SPoP5UCpCI-tFUm-N3dqVMq3zglO
        From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=JdWzK2FV5lAutZAVWzV9I.zplB.iEUp3
        To: <sip:5001@10.21.31.3>
        Contact: <sip:2379@10.21.30.220:5060>
        Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD
        [Generated Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD]
        CSeq: 24522 INVITE
        Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
        Supported: 100rel, timer, replaces, norefersub
        Session-Expires: 1800
        Min-SE: 90
        Max-Forwards: 70
        User-Agent: XEAUDIO-4B 1.6.1 (20191118_08h29)
        Content-Type: application/sdp
        Content-Length:   240
    Message Body
        Session Description Protocol
            Session Description Protocol Version (v): 0
            Owner/Creator, Session Id (o): 2379 1965736325 1965736325 IN IP4 10.21.30.220
            Session Name (s): Asterisk
            Connection Information (c): IN IP4 10.21.30.220
            Time Description, active time (t): 0 0
            Media Description, name and address (m): audio 10800 RTP/AVP 8 101
            Media Attribute (a): rtpmap:8 PCMA/8000
            Media Attribute (a): rtpmap:101 telephone-event/8000
            Media Attribute (a): fmtp:101 0-16
            Media Attribute (a): ptime:20
            Media Attribute (a): maxptime:150
            Media Attribute (a): sendrecv
            [Generated Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD]

**RESPONSE 100 ,180 and 200/OK :**

SIP/2.0 180 Ringing
Feb 18 12:23:10.923 - Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Feb 18 12:23:10.923 - Contact: <sip:xxxxx.xxxx.xxx>
Feb 18 12:23:10.923 - User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Feb 18 12:23:10.923 - P-Asserted-Identity: "Name Surname" <sip:5001@xxxxx;user=phone>
Feb 18 12:23:10.923 - To: <sip:5001@10.21.31.3>;tag=f8b8c78f2138aa42c0e8c68aa67b8070
Feb 18 12:23:10.923 - From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=JdWzK2FV5lAutZAVWzV9I.zplB.iEUp3
Feb 18 12:23:10.923 - Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD
Feb 18 12:23:10.923 - CSeq: 24522 INVITE
Feb 18 12:23:10.923 - Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjtxW1SPoP5UCpCI-tFUm-N3dqVMq3zglO
Feb 18 12:23:10.923 - Content-Length: 0
Feb 18 12:23:10.923 - Content-Length:  0

SIP/2.0 200 OK
Feb 18 12:23:13.277 - Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Feb 18 12:23:13.277 - Contact: <sip:xxxxx.xxxx.xxx>
Feb 18 12:23:13.277 - Supported: replaces, timer, path, 100rel
Feb 18 12:23:13.277 - User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Feb 18 12:23:13.277 - Session-Expires: 1800;refresher=uas
Feb 18 12:23:13.277 - P-Asserted-Identity: "Name Surname" <sip:5001@xxxxx;user=phone>
Feb 18 12:23:13.277 - Content-Type: application/sdp
Feb 18 12:23:13.277 - To: <sip:5001@10.21.31.3>;tag=f8b8c78f2138aa42c0e8c68aa67b8070
Feb 18 12:23:13.277 - From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=JdWzK2FV5lAutZAVWzV9I.zplB.iEUp3
Feb 18 12:23:13.277 - Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD
Feb 18 12:23:13.277 - CSeq: 24522 INVITE
Feb 18 12:23:13.277 - Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjtxW1SPoP5UCpCI-tFUm-N3dqVMq3zglO
Feb 18 12:23:13.277 - Content-Length: 213
Feb 18 12:23:13.277 - Content-Type: application/sdp
Feb 18 12:23:13.277 - Content-Length:   213
Feb 18 12:23:13.277 - 
Feb 18 12:23:13.277 - v=0
Feb 18 12:23:13.277 - o=OXE 1582024989 1582024989 IN IP4 10.21.31.3
Feb 18 12:23:13.277 - s=abs
Feb 18 12:23:13.277 - c=IN IP4 10.33.30.64
Feb 18 12:23:13.277 - t=0 0
Feb 18 12:23:13.277 - m=audio 10174 RTP/AVP 8 101
Feb 18 12:23:13.277 - a=sendrecv
Feb 18 12:23:13.277 - a=rtpmap:8 PCMA/8000
Feb 18 12:23:13.277 - a=rtpmap:101 telephone-event/8000
Feb 18 12:23:13.277 - a=ptime:20
Feb 18 12:23:13.277 - a=maxptime:30


SIP/2.0 200 OK
Feb 18 12:23:13.278 - Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Feb 18 12:23:14.279 - Supported: replaces, timer, path, 100rel
Feb 18 12:23:14.279 - User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Feb 18 12:23:14.279 - Session-Expires: 1800;refresher=uas
Feb 18 12:23:14.279 - P-Asserted-Identity: "Name Surname" <sip:5001@xxxxx;user=phone>
Feb 18 12:23:14.279 - Content-Type: application/sdp
Feb 18 12:23:14.279 - To: <sip:5001@10.21.31.3>;tag=f8b8c78f2138aa42c0e8c68aa67b8070
Feb 18 12:23:14.279 - From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=JdWzK2FV5lAutZAVWzV9I.zplB.iEUp3
Feb 18 12:23:14.279 - Call-ID: QYeXrRsRao0fxL2Lg4YmB.WSguptXHXD
Feb 18 12:23:14.279 - CSeq: 24522 INVITE
Feb 18 12:23:14.279 - Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjtxW1SPoP5UCpCI-tFUm-N3dqVMq3zglO
Feb 18 12:23:14.279 - Content-Length: 213
Feb 18 12:23:14.279 - Content-Type: application/sdp
Feb 18 12:23:14.279 - Content-Length:   213
Feb 18 12:23:14.279 - 
Feb 18 12:23:14.279 - v=0
Feb 18 12:23:14.279 - o=OXE 1582024989 1582024989 IN IP4 10.21.31.3
Feb 18 12:23:14.279 - s=abs
Feb 18 12:23:14.279 - c=IN IP4 10.33.30.64
Feb 18 12:23:14.279 - t=0 0
Feb 18 12:23:14.279 - m=audio 10174 RTP/AVP 8 101
Feb 18 12:23:14.279 - a=sendrecv
Feb 18 12:23:14.279 - a=rtpmap:8 PCMA/8000
Feb 18 12:23:14.279 - a=rtpmap:101 telephone-event/8000
Feb 18 12:23:14.279 - a=ptime:20
Feb 18 12:23:14.279 - a=maxptime:30

jcolp · February 18, 2020, 2:35pm

You’d need to provide the actual “pjsip set logger on” output showing what Asterisk is doing and seeing.

ghost00 · February 18, 2020, 2:41pm

Ok , i try to got it
Thx

ghost00 · February 18, 2020, 4:33pm

below pjsip set debug on

login as: root
root@10.21.30.220's password:
root@XE2510d44:~# rasterisk -vvvvvvvvdddddddddddd
Parsing /etc/asterisk/asterisk.conf
Seeding global EID '00:0e:af:51:0d:44' from 'br0' using 'siocgifhwaddr'
Asterisk certified/13.18-cert3, Copyright (C) 1999 - 2014, Digium, Inc. and othe                                                                             rs.
Created by Mark Spencer <markster@digium.com>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for detail                                                                             s.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk certified/13.18-cert3 currently running on XE2510d44 (pid                                                                              = 972)
Core debug was 3 and is now 12.
XE2510d44*CLI> pjsip set logger on
PJSIP Logging enabled
    -- Called PJSIP/5105@ExtServer1
<--- Transmitting SIP request (901 bytes) to UDP:10.21.31.3:5060 --->
INVITE sip:5105@10.21.31.3 SIP/2.0
Via: SIP/2.0/UDP 10.21.30.220:5060;rport;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNS                                                                             w7.NRAUbO
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
To: <sip:5105@10.21.31.3>
Contact: <sip:2379@10.21.30.220:5060>
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PR                                                                             ACK, REGISTER, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: XEAUDIO-4B 1.6.1 (20191118_08h29)
Content-Type: application/sdp
Content-Length:   240

v=0
o=2379 1620851181 1620851181 IN IP4 10.21.30.220
s=Asterisk
c=IN IP4 10.21.30.220
t=0 0
m=audio 10800 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

<--- Received SIP response (339 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 100 Trying
To: <sip:5105@10.21.31.3>
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0




<--- Received SIP response (616 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 180 Ringing
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDAT                                                                             E
Contact: sip:xxxxxxxxxx.xxxxx.xxx
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0


    -- PJSIP/ExtServer1-0000001f is ringing

[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:5670 set_format: Unable                                                                              to find a codec translation path: (slin) -> (alaw|h264)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: indications.c:156 playtones_alloc:                                                                              Unable to set 'Castel/1073741836' to signed linear format (write)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:4770 indicate_data_inter                                                                             nal: Unable to handle indication 3 for 'Castel/1073741836'


<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

    -- PJSIP/ExtServer1-0000001f answered Castel/1073741836




<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30


<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "NAME Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

XE2510d44*CLI>

jcolp · February 18, 2020, 4:37pm

Some of your log is cut off at the end, leaving out information.

ghost00 · February 18, 2020, 4:50pm

Ok , below the entire log without cut ( Good luck)

login as: root
root@10.21.30.220's password:
root@XE2510d44:~# rasterisk -vvvvvvvvdddddddddddd
Parsing /etc/asterisk/asterisk.conf
Seeding global EID '00:0e:af:51:0d:44' from 'br0' using 'siocgifhwaddr'
Asterisk certified/13.18-cert3, Copyright (C) 1999 - 2014, Digium, Inc. and othe                                                                             rs.
Created by Mark Spencer <markster@digium.com>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for detail                                                                             s.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk certified/13.18-cert3 currently running on XE2510d44 (pid                                                                              = 972)
Core debug was 3 and is now 12.
XE2510d44*CLI> pjsip set logger on
PJSIP Logging enabled
[Feb 18 17:09:28] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Prepar                                                                             ing Msg SIG_MSG for sending (type=22)
[Feb 18 17:09:28] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sendin                                                                             g message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:09:28] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 1                                                                             19 bytes to /tmp/channel-client of the 119 expected
[Feb 18 17:09:39] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=22)
[Feb 18 17:09:39] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:09:39] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 119 bytes to /tmp/channel-client of the 119 expected
[Feb 18 17:09:50] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Prepar                                                                             ing Msg SIG_MSG for sending (type=22)
[Feb 18 17:09:50] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sendin                                                                             g message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:09:50] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 1                                                                             19 bytes to /tmp/channel-client of the 119 expected
[Feb 18 17:09:59] NOTICE[1286]: libipc:1 channel_prepare_msg_for_reading: Readin                                                                             g Msg CMT_SIG_MSG
[Feb 18 17:09:59] NOTICE[1286]: libipc:1 channel_prepare_msg_for_reading: After                                                                              Parsing: got Msg CMT_SIG_MSG (type=1)
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2452 castel_input_socket_read: Received : 2 , id : 0 , fd : 0 , evntts : 0
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2236 castel_factory_process_message: channel  callid : 1073741836
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2169 castel_channel_process_message: CSM_CALL
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2040 castel_channel_handle_call_from_user: Caller=2379, Called=call2sipviaserve                                                                             r5105, rtp=10800, Context=castelOutgoing label=Poste XEAUDIO-4B_PR:2
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2053 castel_channel_handle_call_from_user:   Codecs supported       : (alaw|h26                                                                             4)
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1861 castel_new: Using Audio format : alaw
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1872 castel_new: fake_sockaddr =  0.0.0.0:0
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/castel_engine_rtp/castel_                                                                             engine_rtp.c:3494 ast_rtp_new: flux audio port 10800
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1880 castel_new: audio rtp instance allocated
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/castel_engine_rtp/castel_                                                                             engine_rtp.c:3500 ast_rtp_new: flux video port 10802
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1889 castel_new: video rtp instance allocated
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1897 castel_new: audio rtp fd set
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1903 castel_new: video rtp fd set
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1912 castel_new: technical stuff done
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :1924 castel_new: castel_factory_add_channel : added
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2060 castel_channel_handle_call_from_user: new channel successfully allocated
[Feb 18 17:09:59] NOTICE[1286]: ../../asterisk-modules/chan_castel/chan_castel.c                                                                             :2075 castel_channel_handle_call_from_user: channel started : Castel/1073741836
    -- Executing [call2sipviaserver5105@castelOutgoing:1] NoOp("Castel/107374183                                                                             6", "Starting Server call to SIP/5105") in new stack
    -- Executing [call2sipviaserver5105@castelOutgoing:2] Macro("Castel/10737418                                                                             36", "call-via-server,5105") in new stack
    -- Executing [s@macro-call-via-server:1] Dial("Castel/1073741836", "PJSIP/51                                                                             05@ExtServer1") in new stack
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: chan_pjsip.c:137 SaveRequestor: Cha                                                                             nnel name requestor : 'Castel/1073741836'
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: chan_pjsip.c:145 SaveRequestor: Cha                                                                             nnel name requestor : 'Castel/1073741836' copy 1981847656
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3760 castel_get_vrtp_peer: Called
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:4070 castel_get_codec: Called
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:4086 castel_get_codec: Castel/1073741836 Channel codec = (alaw|h26                                                                             4) .
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:4091 castel_get_codec: Packetization sizes read 0 write 0.
    -- Called PJSIP/5105@ExtServer1
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WR                                                                             ITE  datalen=4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WR                                                                             ITE  datalen=4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:09:59] NOTICE[1160]: res_pjsip_sdp_rtp.c:242 create_rtp: stream type                                                                              :audio
[Feb 18 17:09:59] NOTICE[1160]: res_pjsip_sdp_rtp.c:247 create_rtp: stream type                                                                              :audio type_flux:3
[Feb 18 17:09:59] NOTICE[1160]: ../../asterisk-modules/castel_engine_rtp/castel_                                                                             engine_rtp.c:3494 ast_rtp_new: flux audio port 10800
<--- Transmitting SIP request (901 bytes) to UDP:10.21.31.3:5060 --->
INVITE sip:5105@10.21.31.3 SIP/2.0
Via: SIP/2.0/UDP 10.21.30.220:5060;rport;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNS                                                                             w7.NRAUbO
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
To: <sip:5105@10.21.31.3>
Contact: <sip:2379@10.21.30.220:5060>
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PR                                                                             ACK, REGISTER, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: XEAUDIO-4B 1.6.1 (20191118_08h29)
Content-Type: application/sdp
Content-Length:   240

v=0
o=2379 1620851181 1620851181 IN IP4 10.21.30.220
s=Asterisk
c=IN IP4 10.21.30.220
t=0 0
m=audio 10800 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3006 castel_indicate: channel state: 4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3134 castel_indicate: Got AST_CONTROL_CONNECTED_LINE
<--- Received SIP response (339 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 100 Trying
To: <sip:5105@10.21.31.3>
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0


[Feb 18 17:09:59] NOTICE[1160]: chan_pjsip.c:2705 chan_pjsip_incoming_response:                                                                              buf is : SIP/2.0 100 Trying
To: <sip:5105@10.21.31.3>
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0
Content-Length:  0


[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3006 castel_indicate: channel state: 4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3040 castel_indicate: not implemented indication AST_CONTROL_PVT_C                                                                             AUSE_CODE
<--- Received SIP response (616 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 180 Ringing
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDAT                                                                             E
Contact: sip:xxxxxxxxxx.xxxxx.xxx
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0


[Feb 18 17:09:59] NOTICE[1160]: chan_pjsip.c:2705 chan_pjsip_incoming_response:                                                                              buf is : SIP/2.0 180 Ringing
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDAT                                                                             E
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3O                                                                             TDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4                                                                             bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 0
Content-Length:  0


[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3006 castel_indicate: channel state: 4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3040 castel_indicate: not implemented indication AST_CONTROL_PVT_C                                                                             AUSE_CODE
    -- PJSIP/ExtServer1-0000001f is ringing
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3006 castel_indicate: channel state: 4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3029 castel_indicate: implemented AST_CONTROL_RINGING
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_se                                                                             nding: Preparing Msg SIG_MSG for sending (type=2)
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_s                                                                             ocket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encod                                                                             ing="UTF-8"?>
<ChannelSigMsg type="2">
  <SIGParam type="1" value="1073741836"/>
</ChannelSigMsg>

[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_s                                                                             ocket: Sent 126 bytes to /tmp/channel-client of the 126 expected
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WR                                                                             ITE  datalen=4
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1006 SetCastelChannelFromRequestor: local  addresse : '0.0.0.0:108                                                                             00'
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1010 SetCastelChannelFromRequestor: remote  addresse : '(null)'
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1013 SetCastelChannelFromRequestor: Remote address null
[Feb 18 17:09:59] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/                                                                             chan_castel.c:1217 ParseRequestor: Channel  requestor  rtp not set yet
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:5670 set_format: Unable                                                                              to find a codec translation path: (slin) -> (alaw|h264)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: indications.c:156 playtones_alloc:                                                                              Unable to set 'Castel/1073741836' to signed linear format (write)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:4770 indicate_data_inter                                                                             nal: Unable to handle indication 3 for 'Castel/1073741836'
[Feb 18 17:10:01] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Prepar                                                                             ing Msg SIG_MSG for sending (type=22)
[Feb 18 17:10:01] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sendin                                                                             g message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:10:01] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 1                                                                             19 bytes to /tmp/channel-client of the 119 expected
<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

ghost00 · February 18, 2020, 4:51pm

      > 0x2675b08 -- Strict RTP learning after remote address set to: 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[1160]: res_pjsip_sdp_rtp.c:1477 apply_negotiated_sdp_stream: Bandwith count  l : 0  ### r : 0
[Feb 18 17:10:09] NOTICE[1160]: chan_pjsip.c:2705 chan_pjsip_incoming_response: buf is : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3006 castel_indicate: channel state: 4
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3040 castel_indicate: not implemented indication AST_CONTROL_PVT_CAUSE_CODE
    -- PJSIP/ExtServer1-0000001f answered Castel/1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WRITE  datalen=4
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1006 SetCastelChannelFromRequestor: local  addresse : '10.21.30.220:10800'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1010 SetCastelChannelFromRequestor: remote  addresse : '10.33.30.87:10084'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 1
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1231 ParseRequestor: redirip  addresse  set to : '10.33.30.87:10084'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1274 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1277 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1283 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1305 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1308 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1326 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1326 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1326 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1352 ParseRequestor: Payload set to 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 0 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WRITE  datalen=4
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:2797 castel_answer: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:2799 castel_answer: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:2806 castel_answer: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:2824 castel_answer: mon audio_address : "10.33.30.87:10084" alaw, ma video_address : ":-1" no video codec
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=3)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="3">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 446 bytes to /tmp/channel-client of the 446 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3006 castel_indicate: channel state: 6
[Feb 18 17:10:09] WARNING[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3141 castel_indicate: Don't know how to display condition -1 on Castel/1073741836
    -- Channel PJSIP/ExtServer1-0000001f joined 'simple_bridge' basic-bridge <3a02478f-fb22-4c74-a53a-702e584996d8>
    -- Channel Castel/1073741836 joined 'simple_bridge' basic-bridge <3a02478f-fb22-4c74-a53a-702e584996d8>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

ghost00 · February 18, 2020, 4:51pm

And the last

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3760 castel_get_vrtp_peer: Called
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WRITE  datalen=4
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3657 castel_setoption: AST_OPTION_FORMAT_READ AST_OPTION_FORMAT_WRITE  datalen=4
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1206 ParseRequestor: Parse  requestor  state :3
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:646 castel_set_dynamic_config: Ip : <10.33.30.87> , success: <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:701 castel_set_dynamic_config: Codec audio : <8> ,nb codec : <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:681 castel_set_dynamic_config: Codec audio : <101> ,nb codec : <2> ,nbpoint :<2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:710 castel_set_dynamic_config: Port audio : <10084> ,nb codec : <2>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:722 castel_set_dynamic_config: Cvideo : <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:764 castel_set_dynamic_config: Port video : <0> ,nb codec : <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1381 ParseRequestor: nb occurence audio is :   <1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1384 ParseRequestor: Rang 0 occurence audio is :   <23>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1390 ParseRequestor: nb occurence video is :   <0>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1412 ParseRequestor: 0-bwinf :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:308 parse_bandwidth_header: bandwith modifier :  , bandwith value : 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1415 ParseRequestor: Dynamic config set bandwith :   <> -- <0> 
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 0-rtpmap :   <8 PCMA/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:387 parse_rtpmap_header: payload : 8 , Name codec : PCMA , num : 8000
>  b 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 1-rtpmap :   <101 telephone-event/8000
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1433 ParseRequestor: 2-rtpmap :   <>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:777 castel_construct_address: set address to 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1477 ParseRequestor: castel_construct_address Ok
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1026 ExtractAndCmpRtpInstance: redirip  addresse : '10.33.30.87:10084'  , redirip pre adresse : 10.33.30.87:10084
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1493 ParseRequestor: -Rtp add no change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1156 SetDtmfMode: dtmf mode is : 0 , nb_codecs : 1, mode 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:961 ChannelCodecComp: ChannelCodecComp
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1549 ParseRequestor: Codec  change
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1041 SetCastelChannelCodecFromRequestor: SetCastelChannelCodecFromRequestor
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:1624 ParseRequestor: Call  castel_channel_streams_changed with requestor : 'PJSIP/ExtServer1-0000001f'
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3477 castel_channel_streams_changed: Header peer : SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: <sip:xxxxxxxxxx.xxxxx.xxx>
Supported: replaces, timer, path, 100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;rport=5060;received=10.21.30.220;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213
Content-Type: application/sdp
Content-Length:   213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3479 castel_channel_streams_changed: 1-User peer agent : OmniPCX Enterprise R11.1 l1.301.31.a
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3486 castel_channel_streams_changed: From peer : "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3525 castel_channel_streams_changed: audio_codec_name_ptr : alaw payload number 8 , 0
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3561 castel_channel_streams_changed: set payload audio, video  <8>,<-1>
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3395 castel_send_stream_changed: CallId=1073741836
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3412 castel_send_stream_changed: audio(alaw:8): "10.33.30.87:10084" video(:-1): "(null):0"
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=20)
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="20">
  <SIGParam type="1" value="1073741836"/>
  <SIGParam type="16" value="10.33.30.87"/>
  <SIGParam type="7" value="10084"/>
  <SIGParam type="17" value="(null)"/>
  <SIGParam type="12" value="alaw"/>
  <SIGParam type="18" value="8"/>
  <SIGParam type="27" value="101"/>
  <SIGParam type="28" value="OmniPCX Enterprise R11.1 l1.301.31.a&#13;"/>
  <SIGParam type="29" value="&quot;Poste XEAUDIO-4B_PR:2&quot; &lt;sip:2379@10.21.31.3&gt;"/>
</ChannelSigMsg>

[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: libipc:1 channel_send_msg_to_unix_socket: Sent 522 bytes to /tmp/channel-client of the 522 expected
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3006 castel_indicate: channel state: 6
[Feb 18 17:10:09] NOTICE[11754][C-0000001f]: ../../asterisk-modules/chan_castel/chan_castel.c:3125 castel_indicate: Got AST_CONTROL_SRCCHANGE! Inform GStreamer??
<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:12] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=22)
[Feb 18 17:10:12] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:10:12] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 119 bytes to /tmp/channel-client of the 119 expected
<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

<--- Received SIP response (933 bytes) from UDP:10.21.31.3:5060 --->
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:xxxxxxxxxx.xxxxx.xxx
Supported: replaces,timer,path,100rel
User-Agent: OmniPCX Enterprise R11.1 l1.301.31.a
Session-Expires: 1800;refresher=uas
P-Asserted-Identity: "Name Surname" <sip:5105@xxxxxxxxxx;user=phone>
Content-Type: application/sdp
To: <sip:5105@10.21.31.3>;tag=0024cfd47479e1547204683d567e6d6c
From: "Poste XEAUDIO-4B_PR:2" <sip:2379@10.21.31.3>;tag=7eQvFNMSOCJTBAtLB8Kuz-3OTDuiDCzO
Call-ID: Lz-auO3noSM4RX2MQNVKtCjwRCY23wba
CSeq: 31334 INVITE
Via: SIP/2.0/UDP 10.21.30.220:5060;received=10.21.30.220;rport=5060;branch=z9hG4bKPjBXMi2tKsunEv9Wh2fDWUuNSw7.NRAUbO
Content-Length: 213

v=0
o=OXE 1582042199 1582042199 IN IP4 10.21.31.3
s=abs
c=IN IP4 10.33.30.87
t=0 0
m=audio 10084 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=maxptime:30

[Feb 18 17:10:23] NOTICE[1286]: libipc:1 channel_prepare_msg_for_sending: Preparing Msg SIG_MSG for sending (type=22)
[Feb 18 17:10:23] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sending message destFile /tmp/channel-client: 2:<?xml version="1.0" encoding="UTF-8"?>
<ChannelSigMsg type="22">
  <SIGParam type="30" value="0"/>
</ChannelSigMsg>

[Feb 18 17:10:23] NOTICE[1286]: libipc:1 channel_send_msg_to_unix_socket: Sent 119 bytes to /tmp/channel-client of the 119 expected
XE2510d44*CLI>

kharwell · February 19, 2020, 12:04am

This log output is really hard to follow, but I did see the following (although hard to tell if it is part of the problem):

[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:5670 set_format: Unable to find a codec translation path: (slin) -> (alaw|h264)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: indications.c:156 playtones_alloc: Unable to set 'Castel/1073741836' to signed linear format (write)
[Feb 18 17:09:59] WARNING[11754][C-0000001f]: channel.c:4770 indicate_data_internal: Unable to handle indication 3 for 'Castel/1073741836'

It appears you are using a third party module, or are running a custom patched Asterisk. If this is the case I’d recommend starting with base Asterisk, and make sure you can connect a call, and ensure your dialplan works as expected.

ghost00 · February 19, 2020, 7:51am

Hi all ,
@kharwell : tthis is not the problem , this warning appear all the time .

There is indeed a custom channel but the system works in most cases.
I wanted your expert advice on this particular SIP exchanges.

Thx a lot

G

kharwell · February 19, 2020, 6:41pm

Right, but there is no way for us to tell if that custom module is interfering with things and potentially causing the problem. In order for us to help you’ll first need to determine if the problem is in Asterisk or not.

Have you experienced the problem before without the custom module loaded?

Verbose, and debug logging might also help here. It appears you did enable extra debug and verbose logging, but I’m not seeing it in the output. Have you set it to output in logger.conf?

See Logging Configuration and Collecting+Debug+Information for more information on how to do that.

ghost00 · February 24, 2020, 2:37pm

Hi all ,
Ok , thanks i will try to access log
Thx

system · March 25, 2020, 2:40pm

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.

Topic		Replies	Views
Trying and ACK udp SIP packets ignored on call transfer (pjsip) Asterisk SIP	8	1320	October 23, 2018
Why isn't Asterisk accepting these SIP ACKS? Asterisk Support	4	430	November 14, 2013
Pjsip asterisk 18.4 tcp invite internal error Asterisk SIP	3	467	June 18, 2021
Pjsip responds with a 488 Not Acceptable Here Asterisk SIP	3	2721	September 30, 2020
Asterisk 13.7.2 pjsip 2.4.5 unexpected BYE with SIP cause 58 while answering Asterisk Support	1	2314	March 21, 2016

PJSIP : Expected ACK not send

Related topics