[Sep 16 13:29:53] Asterisk 18.6.0 built by root @ dev on a x86_64 running Linux on 2021-09-15 20:09:52 UTC
[Sep 16 13:29:53] DEBUG[443061] config.c: Parsing /etc/asterisk/logger.conf
[Sep 16 13:29:53] VERBOSE[443061] logger.c: Asterisk Queue Logger restarted
[Sep 16 13:30:00] DEBUG[443013] threadpool.c: Worker thread idle timeout reached. Dying.
[Sep 16 13:30:00] DEBUG[443015] threadpool.c: Worker thread idle timeout reached. Dying.
[Sep 16 13:30:00] DEBUG[443008] threadpool.c: Destroying worker thread 7
[Sep 16 13:30:00] DEBUG[443008] threadpool.c: Destroying worker thread 9
[Sep 16 13:30:00] DEBUG[443014] threadpool.c: Worker thread idle timeout reached. Dying.
[Sep 16 13:30:00] DEBUG[443008] threadpool.c: Destroying worker thread 8
[Sep 16 13:30:00] DEBUG[443022] res_pjsip_registrar.c: Woke up at 1631799000  Interval: 30
[Sep 16 13:30:00] DEBUG[443022] res_pjsip_registrar.c: Expiring 0 contacts
[Sep 16 13:30:00] DEBUG[443016] threadpool.c: Worker thread idle timeout reached. Dying.
[Sep 16 13:30:00] DEBUG[442981] threadpool.c: Destroying worker thread 10
[Sep 16 13:30:00] DEBUG[443012] threadpool.c: Worker thread idle timeout reached. Dying.
[Sep 16 13:30:00] DEBUG[443008] threadpool.c: Destroying worker thread 6
[Sep 16 13:30:02] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP request (870 bytes) from UDP:192.168.1.253:65513 --->
INVITE sip:200@dev SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:65513;branch=z9hG4bK-524287-1---74fc1d994ab71f77;rport
Max-Forwards: 70
Contact: <sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4>
To: <sip:200@dev>
From: <sip:801@dev>;tag=9fec2e48
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
CSeq: 1 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: Bria 5 release 5.8.4 stamp 104679
Content-Length: 303

v=0
o=- 1631799002338771 1 IN IP4 192.168.1.253
s=Bria 5 release 5.8.4 stamp 104679
c=IN IP4 1.2.3.4
t=0 0
m=audio 53158 RTP/AVP 9 120 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=1 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg INVITE/cseq=1 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '801' domain 'dev'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Identified by From username '801' domain 'dev'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '801-iauth'.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_authenticator_digest.c: Realm: asterisk  Username: 801  Result: NOAUTH
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.131' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.131' and port ''.
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:02] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP response (536 bytes) to UDP:192.168.1.253:65513 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.253:65513;rport=65513;received=192.168.1.253;branch=z9hG4bK-524287-1---74fc1d994ab71f77
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
From: <sip:801@dev>;tag=9fec2e48
To: <sip:200@dev>;tag=z9hG4bK-524287-1---74fc1d994ab71f77
CSeq: 1 INVITE
WWW-Authenticate: Digest realm="asterisk",nonce="1631799002/99bfc5e397fcfb3a52be4a27b43263e2",opaque="54894e9653e60cae",algorithm=md5,qop="auth"
Server: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:02] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP request (359 bytes) from UDP:192.168.1.253:65513 --->
ACK sip:200@dev SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:65513;branch=z9hG4bK-524287-1---74fc1d994ab71f77;rport
Max-Forwards: 70
To: <sip:200@dev>;tag=z9hG4bK-524287-1---74fc1d994ab71f77
From: <sip:801@dev>;tag=9fec2e48
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
CSeq: 1 ACK
Content-Length: 0


[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg ACK/cseq=1 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg ACK/cseq=1 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '801' domain 'dev'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Identified by From username '801' domain 'dev'
[Sep 16 13:30:02] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP request (1156 bytes) from UDP:192.168.1.253:65513 --->
INVITE sip:200@dev SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:65513;branch=z9hG4bK-524287-1---ce49664d182753f9;rport
Max-Forwards: 70
Contact: <sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4>
To: <sip:200@dev>
From: <sip:801@dev>;tag=9fec2e48
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
CSeq: 2 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: Bria 5 release 5.8.4 stamp 104679
Authorization: Digest username="801",realm="asterisk",nonce="1631799002/99bfc5e397fcfb3a52be4a27b43263e2",uri="sip:200@dev",response="7d98729e9f80607d53f28be200a7468b",cnonce="fea88e398a5b65f39e102396138f7d97",nc=00000001,qop=auth,algorithm=md5,opaque="54894e9653e60cae"
Content-Length: 303

v=0
o=- 1631799002338771 1 IN IP4 192.168.1.253
s=Bria 5 release 5.8.4 stamp 104679
c=IN IP4 1.2.3.4
t=0 0
m=audio 53158 RTP/AVP 9 120 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=2 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg INVITE/cseq=2 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '801' domain 'dev'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Identified by From username '801' domain 'dev'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '801-iauth'.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_authenticator_digest.c: Calculated nonce 1631799002/99bfc5e397fcfb3a52be4a27b43263e2. Actual nonce is 1631799002/99bfc5e397fcfb3a52be4a27b43263e2
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_authenticator_digest.c: Realm: asterisk  Username: 801  Result: SUCCESS
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.131' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.131' and port ''.
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  (null session) Request: INVITE 
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Request: 
[Sep 16 13:30:02] DEBUG[443011] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg INVITE/cseq=2 (rdata0x7f96b8023c88)
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  Direct media no glare mitigation
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Call (UDP:192.168.1.253:65513) to extension '200' sending 100 Trying
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Method is INVITE, Response is 100 Trying
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801
[Sep 16 13:30:02] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP response (344 bytes) to UDP:192.168.1.253:65513 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.253:65513;rport=65513;received=192.168.1.253;branch=z9hG4bK-524287-1---ce49664d182753f9
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
From: <sip:801@dev>;tag=9fec2e48
To: <sip:200@dev>
CSeq: 2 INVITE
Server: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 Event: TSX_STATE  Inv State: INCOMING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801()'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: There is no transaction involved in this state change
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is INCOMING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: 801: Source of transaction state change is TX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 TSX State: Proceeding  Inv State: INCOMING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801()'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9610013d58
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current transaction state is Proceeding
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The transaction state change event is TX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is INCOMING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 TSX State: Proceeding  Inv State: INCOMING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Topology: Pending: (null topology)  Active: (null topology)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Media count: 1
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Processing stream 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Using audio-0 for new stream name
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Using new stream 0:audio-0:audio:sendrecv (nothing)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 Adding position 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Creating new media session
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Setting media session as default for audio
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Done
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Negotiating incoming SDP media stream 0:audio-0:audio:sendrecv (nothing) using audio SDP handler
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  801
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '1.2.3.4' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '1.2.3.4' and port ''.
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '0.0.0.0' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '0.0.0.0' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c: Transport default-udp-transport bound to 0.0.0.0: Using it for RTP media.
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f9610031bd0'
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) RTP allocated port 10702
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE creating session 0.0.0.0:10702 (10702)
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE create
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE add system candidates
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.131' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.131' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE add candidate: 192.168.1.131:10702, 2130706431
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: RTP instance '0x7f9610031bd0' is setup and ready to go
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE stopped
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting 'dev' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host 'dev' and port ''.
[Sep 16 13:30:02] DEBUG[443011] acl.c: Multiple addresses. Using the first only
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) RTCP setup on RTP instance
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  801
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7f96483124c0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session/pjsip_session_caps.c: '801' Caps for incoming audio call with pref 'local' - remote: (g722|opus) local: (g722) joint: (g722)
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Crossover copying tx to rx payload mapping 9 (0x25eb448) from 0x7f96483124c0 to 0x7f96483124c0
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x7f961002f888) from 0x7f96483124c0 to 0x7f96483124c0
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Crossover copying tx to rx payload mapping 120 (0x7f961002f928) from 0x7f96483124c0 to 0x7f96483124c0
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying rx payload mapping 9 (0x25eb448) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying rx payload mapping 101 (0x7f961002f888) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying rx payload mapping 120 (0x7f961002f928) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying tx payload mapping 9 (0x25eb448) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying tx payload mapping 101 (0x7f961002f888) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Copying tx payload mapping 120 (0x7f961002f928) from 0x7f96483124c0 to 0x7f9610031da8
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Media stream 0:audio-0:audio:sendrecv (g722) handled by audio
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Done with stream 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Handled? yes
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Processing streams
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Processing stream 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 Adding position 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Using existing media_session
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 Stream: 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  801 Type: audio 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) RTCP ignoring duplicate property
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  RC: 1
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Had handler
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Stream 0:audio-0:audio:sendrecv (g722) added
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Done with 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Adding bundle groups (if available)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Copying connection details
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Processing media 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Media 0 reset
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801: Method is INVITE
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  <initializing>: Formats: (none)
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:02] DEBUG[443011] stasis.c: Creating topic. name: channel:1631799002.0, detail: 
[Sep 16 13:30:02] DEBUG[443011] stasis.c: Topic 'channel:1631799002.0': 0x7f9610035d60 created
[Sep 16 13:30:02] DEBUG[443011] channel.c: Channel 0x7f961003e6f0 'PJSIP/801-00000000' allocated
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  Topology:  <0:audio-0:audio:sendrecv (g722)> Formats: (g722)
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  Compatible? yes
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  PJSIP/801-00000000: MultistreamFormats: (g722)
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  Set native formats but not topology
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  PJSIP/801-00000000:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443011] channel_internal_api.c:  Used provided topology
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  RC: 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Request:  Session: PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  (null session) Handled request INVITE  ? yes
[Sep 16 13:30:02] DEBUG[442980] threadpool.c: Increasing threadpool stasis/pool's size by 1
[Sep 16 13:30:02] DEBUG[443122][C-00000001] pbx.c: Launching 'Queue'
[Sep 16 13:30:02] VERBOSE[443122][C-00000001] pbx.c: Executing [200@default:1] Queue("PJSIP/801-00000000", "200") in new stack
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: queue: 200, options: , url: , announce: , timeout: , agi: , macro: , gosub: , rule: , position: 
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: NO QUEUE_PRIO variable found. Using default.
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: queue: 200, expires: 0, priority: 0
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: Queue 200 has no realtime members defined. No need for update
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Creating topic. name: devicestate:all/Queue:200, detail: 
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Topic 'devicestate:all/Queue:200': 0x7f96040034e0 created
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: Queue '200' Join, Channel 'PJSIP/801-00000000', Position '1'
[Sep 16 13:30:02] WARNING[443122][C-00000001] res_musiconhold.c: Music on Hold class 'default' not found in memory. Verify your configuration.
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: There is 1 available member.
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: It's our turn (PJSIP/801-00000000).
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: PJSIP/801-00000000 is trying to call a queue member.
[Sep 16 13:30:02] DEBUG[443037] app_queue.c: Device 'Queue:200' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: (Parallel) Trying 'PJSIP/801' with metric 0
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  801 Topology:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  801 (null) Topology:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443011] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Sep 16 13:30:02] DEBUG[443011] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Sep 16 13:30:02] DEBUG[443011] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Sep 16 13:30:02] DEBUG[443011] config.c: extract uint from [65513] in [0, 4294967295] gives [65513](0)
[Sep 16 13:30:02] DEBUG[443011] config.c: extract uint from [0] in [0, 86400] gives [0](0)
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  Direct media no glare mitigation
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session/pjsip_session_caps.c: '801' Caps for outgoing audio call with pref 'remote_merge' - remote: (g722) local: (g722) joint: (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  801
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  <initializing>: Formats: (none)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Creating topic. name: channel:1631799002.1, detail: 
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Topic 'channel:1631799002.1': 0x7f9604002e90 created
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel.c: Channel 0x7f9604005d20 'PJSIP/801-00000001' allocated
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  Topology:  <0:audio-0:audio:sendrecv (g722)> Formats: (g722)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  Compatible? yes
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  PJSIP/801-00000001: MultistreamFormats: (g722)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Set native formats but not topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  PJSIP/801-00000001:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Used provided topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  Channel: PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000001 Topology:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  'call' task pushed
[Sep 16 13:30:02] VERBOSE[443122][C-00000001] app_queue.c: Called PJSIP/801
[Sep 16 13:30:02] DEBUG[443122][C-00000001] app_queue.c: (Parallel) Trying 'Local/801@local' with metric 0
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  <initializing>: Formats: (none)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001 Topology:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Creating topic. name: channel:1631799002.2, detail: 
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Topic 'channel:1631799002.2': 0x7f9604011620 created
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Processing streams
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Processing stream 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Adding position 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Creating new media session
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Setting media session as default for audio
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Done
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Stream: 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  PJSIP/801-00000001 Type: audio 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '0.0.0.0' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '0.0.0.0' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c: Transport default-udp-transport bound to 0.0.0.0: Using it for RTP media.
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f9610044410'
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel.c: Channel 0x7f960400e850 'Local/801@local-00000000;1' allocated
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Local/801@local-00000000;1: MultistreamFormats: (g722)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Set native formats but not topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Local/801@local-00000000;1:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Used provided topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  <initializing>: Formats: (none)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Creating topic. name: channel:1631799002.3, detail: 
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) RTP allocated port 19416
[Sep 16 13:30:02] DEBUG[443122][C-00000001] stasis.c: Topic 'channel:1631799002.3': 0x7f9604016a90 created
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE creating session 0.0.0.0:19416 (19416)
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE create
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel.c: Channel 0x7f9604013e30 'Local/801@local-00000000;2' allocated
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Local/801@local-00000000;2: MultistreamFormats: (g722)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Set native formats but not topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Local/801@local-00000000;2:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c:  Used provided topology
[Sep 16 13:30:02] DEBUG[443122][C-00000001] channel_internal_api.c: Channel Call ID changing from [C-00000001] to [C-00000001]
[Sep 16 13:30:02] VERBOSE[443122][C-00000001] app_queue.c: Called Local/801@local
[Sep 16 13:30:02] DEBUG[443124][C-00000001] pbx_variables.c: Result of 'EXTEN' is '801'
[Sep 16 13:30:02] DEBUG[443124][C-00000001] pbx.c: Launching 'NoOp'
[Sep 16 13:30:02] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:1] NoOp("Local/801@local-00000000;2", " *** 801 *** ") in new stack
[Sep 16 13:30:02] DEBUG[443124][C-00000001] pbx.c: Launching 'Wait'
[Sep 16 13:30:02] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:2] Wait("Local/801@local-00000000;2", "3") in new stack
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE add system candidates
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting '192.168.1.131' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host '192.168.1.131' and port ''.
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE add candidate: 192.168.1.131:19416, 2130706431
[Sep 16 13:30:02] DEBUG[443011] rtp_engine.c: RTP instance '0x7f9610044410' is setup and ready to go
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE stopped
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: Splitting 'dev' into...
[Sep 16 13:30:02] DEBUG[443011] netsock2.c: ...host 'dev' and port ''.
[Sep 16 13:30:02] DEBUG[443011] acl.c: Multiple addresses. Using the first only
[Sep 16 13:30:02] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) RTCP setup on RTP instance
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_sdp_rtp.c:  RC: 1
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Handled
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Stream 0:audio-0:audio:sendrecv (g722) added
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Done with 0:audio-0:audio:sendrecv (g722)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Adding bundle groups (if available)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Copying connection details
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Processing media 0
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Media 0 reset
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Method is INVITE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '192.168.1.253'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip/pjsip_resolver.c: Transport type for target '192.168.1.253' is 'UDP transport'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip/pjsip_resolver.c: Target '192.168.1.253' is an IP address, skipping resolution
[Sep 16 13:30:02] VERBOSE[443122][C-00000001] app_queue.c: PJSIP/801-00000001 connected line has changed. Saving it until answer for PJSIP/801-00000000
[Sep 16 13:30:02] DEBUG[443011] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 192.168.1.131:5060 (this may be re-written again later)
[Sep 16 13:30:02] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP request (956 bytes) to UDP:192.168.1.253:65513 --->
INVITE sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.131:5060;rport;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>
Contact: <sip:asterisk@192.168.1.131:5060>
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: Asterisk PBX 18.6.0
Content-Type: application/sdp
Content-Length:   239

v=0
o=- 1314579970 1314579970 IN IP4 192.168.1.131
s=Asterisk
c=IN IP4 192.168.1.131
t=0 0
m=audio 19416 RTP/AVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Event: TSX_STATE  Inv State: CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: There is no transaction involved in this state change
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: PJSIP/801-00000001: Source of transaction state change is TX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Calling  Inv State: CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f961004e2f8
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current transaction state is Calling
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The transaction state change event is TX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Calling  Inv State: CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Topology: Pending:  <0:audio-0:audio:sendrecv (g722)>  Active: (null topology)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  RC: 0
[Sep 16 13:30:02] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP response (337 bytes) from UDP:192.168.1.253:65513 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.131:5060;rport=5060;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 INVITE
Content-Length: 0


[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f961003b938 for Response msg 100/INVITE/cseq=10527 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/801-0000005d associated with dialog dlg0x7f961003b938
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Method: INVITE Status: 100
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Proceeding  Inv State: CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f961004e2f8
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current transaction state is Proceeding
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The transaction state change event is RX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Response is 100 Trying
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Status: 100
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Not queueing anything
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Status: 100
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Proceeding  Inv State: CALLING
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Topology: Pending:  <0:audio-0:audio:sendrecv (g722)>  Active: (null topology)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000: Indicated Private Cause Code
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:02] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP response (491 bytes) from UDP:192.168.1.253:65513 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.131:5060;rport=5060;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
Contact: <sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4>
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>;tag=2ecdf0ef
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 INVITE
User-Agent: Bria 5 release 5.8.4 stamp 104679
Allow-Events: talk, hold
Content-Length: 0


[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f961003b938 for Response msg 180/INVITE/cseq=10527 (rdata0x7f96b8000be8)
[Sep 16 13:30:02] DEBUG[443010] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/801-0000005d associated with dialog dlg0x7f961003b938
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Method: INVITE Status: 180
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Event: TSX_STATE  Inv State: EARLY
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: There is no transaction involved in this state change
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is EARLY
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: PJSIP/801-00000001: Source of transaction state change is RX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: PJSIP/801-00000001: Received response
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Response is 180 Ringing
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Status: 180
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Proceeding  Inv State: EARLY
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f961004e2f8
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current transaction state is Proceeding
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The transaction state change event is RX_MSG
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c: The current inv state is EARLY
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Response is 180 Ringing
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Status: 180
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Queueing RINGING
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001: Status: 180
[Sep 16 13:30:02] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Proceeding  Inv State: EARLY
[Sep 16 13:30:02] DEBUG[442992] devicestate.c: No provider found, checking channel drivers for PJSIP - 801
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  Topology: Pending:  <0:audio-0:audio:sendrecv (g722)>  Active: (null topology)
[Sep 16 13:30:02] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:02] DEBUG[442992] devicestate.c: Changing state for PJSIP/801 - state 7 (Ring+Inuse)
[Sep 16 13:30:02] DEBUG[443037] app_queue.c: Device 'PJSIP/801' changed to state '7' (Ring+Inuse) but we don't care because they're not a member of any queue.
[Sep 16 13:30:02] DEBUG[442995] app_queue.c: Removed 801 from pending_members
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000: Indicated Private Cause Code
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:02] VERBOSE[443122][C-00000001] app_queue.c: PJSIP/801-00000001 is ringing
[Sep 16 13:30:02] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '7' (Ring+Inuse)
[Sep 16 13:30:02] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '7' (Ring+Inuse)
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000: Indicated Private Cause Code
[Sep 16 13:30:02] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:05] DEBUG[443124][C-00000001] pbx.c: Launching 'Goto'
[Sep 16 13:30:05] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:3] Goto("Local/801@local-00000000;2", "start") in new stack
[Sep 16 13:30:05] VERBOSE[443124][C-00000001] pbx_builtins.c: Goto (local,801,1)
[Sep 16 13:30:05] DEBUG[443124][C-00000001] pbx_variables.c: Result of 'EXTEN' is '801'
[Sep 16 13:30:05] DEBUG[443124][C-00000001] pbx.c: Launching 'NoOp'
[Sep 16 13:30:05] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:1] NoOp("Local/801@local-00000000;2", " *** 801 *** ") in new stack
[Sep 16 13:30:05] DEBUG[443124][C-00000001] pbx.c: Launching 'Wait'
[Sep 16 13:30:05] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:2] Wait("Local/801@local-00000000;2", "3") in new stack
[Sep 16 13:30:08] DEBUG[443124][C-00000001] pbx.c: Launching 'Goto'
[Sep 16 13:30:08] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:3] Goto("Local/801@local-00000000;2", "start") in new stack
[Sep 16 13:30:08] VERBOSE[443124][C-00000001] pbx_builtins.c: Goto (local,801,1)
[Sep 16 13:30:08] DEBUG[443124][C-00000001] pbx_variables.c: Result of 'EXTEN' is '801'
[Sep 16 13:30:08] DEBUG[443124][C-00000001] pbx.c: Launching 'NoOp'
[Sep 16 13:30:08] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:1] NoOp("Local/801@local-00000000;2", " *** 801 *** ") in new stack
[Sep 16 13:30:08] DEBUG[443124][C-00000001] pbx.c: Launching 'Wait'
[Sep 16 13:30:08] VERBOSE[443124][C-00000001] pbx.c: Executing [801@local:2] Wait("Local/801@local-00000000;2", "3") in new stack
[Sep 16 13:30:08] DEBUG[443061] app_queue.c: Queue 200 has no realtime members defined. No need for update
[Sep 16 13:30:11] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP request (658 bytes) from UDP:192.168.1.253:65513 --->
CANCEL sip:200@dev SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:65513;branch=z9hG4bK-524287-1---ce49664d182753f9;rport
Max-Forwards: 70
To: <sip:200@dev>
From: <sip:801@dev>;tag=9fec2e48
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
CSeq: 2 CANCEL
User-Agent: Bria 5 release 5.8.4 stamp 104679
Authorization: Digest username="801",realm="asterisk",nonce="1631799002/99bfc5e397fcfb3a52be4a27b43263e2",uri="sip:200@dev",response="2a1b1f58b47dacf64b5b2bc16a3dad91",cnonce="8b77a29eb8ab4764a1e86e4cfbd805a0",nc=00000002,qop=auth,algorithm=md5,opaque="54894e9653e60cae"
Content-Length: 0


[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9610014778 for Request msg CANCEL/cseq=2 (rdata0x7f96b8000be8)
[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002a associated with dialog dlg0x7f9610014778
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 TSX State: Trying  Inv State: INCOMING
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000000)'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAS CANCEL transaction involved in this state change is 0x7f9610041c68
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Trying
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is RX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is INCOMING
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000: Method is CANCEL
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:11] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP response (381 bytes) to UDP:192.168.1.253:65513 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.253:65513;rport=65513;received=192.168.1.253;branch=z9hG4bK-524287-1---ce49664d182753f9
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
From: <sip:801@dev>;tag=9fec2e48
To: <sip:200@dev>;tag=618cbcf9-ad55-40a3-ac1e-0068e80697da
CSeq: 2 CANCEL
Server: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 TSX State: Completed  Inv State: INCOMING
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000000)'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAS CANCEL transaction involved in this state change is 0x7f9610041c68
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Completed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is TX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is INCOMING
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 TSX State: Completed  Inv State: INCOMING
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Topology: Pending:  <0:audio-0:audio:sendrecv (g722)>  Active: (null topology)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP response (397 bytes) to UDP:192.168.1.253:65513 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.1.253:65513;rport=65513;received=192.168.1.253;branch=z9hG4bK-524287-1---ce49664d182753f9
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
From: <sip:801@dev>;tag=9fec2e48
To: <sip:200@dev>;tag=618cbcf9-ad55-40a3-ac1e-0068e80697da
CSeq: 2 INVITE
Server: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 Event: TSX_STATE  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000000)'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: There is no transaction involved in this state change
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: PJSIP/801-00000000: Source of transaction state change is TX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 TSX State: Completed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000000)'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9610013d58
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Completed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is TX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Disconnected
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  
[Sep 16 13:30:11] DEBUG[443122][C-00000001] app_queue.c: PJSIP/801-00000000: Nobody answered.
[Sep 16 13:30:11] DEBUG[443000] cdr.c: Finalized CDR for PJSIP/801-00000000 - start 1631799002.630105 answer 0.000000 end 1631799011.093528 dur 8.463 bill 1631799011.093 dispo NO ANSWER
[Sep 16 13:30:11] DEBUG[443000] cdr.c: Finalized CDR for PJSIP/801-00000000 - start 1631799002.631723 answer 0.000000 end 1631799011.093587 dur 8.461 bill 1631799011.093 dispo NO ANSWER
[Sep 16 13:30:11] DEBUG[443122][C-00000001] app_queue.c: Removed 801 from pending_members
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel.c: Channel 0x7f9604005d20 'PJSIP/801-00000001' hanging up.  Refs: 2
[Sep 16 13:30:11] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:11] DEBUG[443122][C-00000001] chan_pjsip.c: AST hangup cause 0 (no match found in PJSIP)
[Sep 16 13:30:11] DEBUG[443122][C-00000001] chan_pjsip.c:  Cause: 0
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel.c: Channel 0x7f960400e850 'Local/801@local-00000000;1' hanging up.  Refs: 2
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel.c: Channel 0x7f960400e850 'Local/801@local-00000000;1' destroying
[Sep 16 13:30:11] DEBUG[443122][C-00000001] stasis.c: Destroying topic. name: channel:1631799002.2, detail: 
[Sep 16 13:30:11] DEBUG[443122][C-00000001] stasis.c: Topic 'channel:1631799002.2': 0x7f9604011620 destroyed
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel_internal_api.c:  <initializing>: MultistreamFormats: (nothing)
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: No provider found, checking channel drivers for Local - 801@local
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000001
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: Changing state for Local/801@local - state 1 (Not in use)
[Sep 16 13:30:11] DEBUG[443122][C-00000001] app_queue.c: Queue '200' Leave, Channel 'PJSIP/801-00000000'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 Response 0
[Sep 16 13:30:11] DEBUG[443122][C-00000001] pbx.c: Spawn extension (default,200,1) exited non-zero on 'PJSIP/801-00000000'
[Sep 16 13:30:11] VERBOSE[443122][C-00000001] pbx.c: Spawn extension (default, 200, 1) exited non-zero on 'PJSIP/801-00000000'
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel.c: Soft-Hanging (0x10) up channel 'PJSIP/801-00000000'
[Sep 16 13:30:11] DEBUG[443124][C-00000001] pbx.c: Spawn extension (local,801,2) exited non-zero on 'Local/801@local-00000000;2'
[Sep 16 13:30:11] VERBOSE[443124][C-00000001] pbx.c: Spawn extension (local, 801, 2) exited non-zero on 'Local/801@local-00000000;2'
[Sep 16 13:30:11] DEBUG[443124][C-00000001] channel.c: Soft-Hanging (0x10) up channel 'Local/801@local-00000000;2'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001: Method is CANCEL
[Sep 16 13:30:11] DEBUG[443122][C-00000001] channel.c: Channel 0x7f961003e6f0 'PJSIP/801-00000000' hanging up.  Refs: 2
[Sep 16 13:30:11] DEBUG[443122][C-00000001] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:11] DEBUG[443124][C-00000001] channel.c: Channel 0x7f9604013e30 'Local/801@local-00000000;2' hanging up.  Refs: 2
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001
[Sep 16 13:30:11] DEBUG[443122][C-00000001] chan_pjsip.c:  Cause: 500
[Sep 16 13:30:11] DEBUG[443124][C-00000001] channel.c: Channel 0x7f9604013e30 'Local/801@local-00000000;2' destroying
[Sep 16 13:30:11] DEBUG[443124][C-00000001] stasis.c: Destroying topic. name: channel:1631799002.3, detail: 
[Sep 16 13:30:11] DEBUG[443124][C-00000001] stasis.c: Topic 'channel:1631799002.3': 0x7f9604016a90 destroyed
[Sep 16 13:30:11] DEBUG[443037] app_queue.c: Device 'Queue:200' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Sep 16 13:30:11] DEBUG[443037] app_queue.c: Device 'Local/801@local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Sep 16 13:30:11] DEBUG[443124][C-00000001] channel_internal_api.c:  <initializing>: MultistreamFormats: (nothing)
[Sep 16 13:30:11] DEBUG[443124][C-00000001] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:11] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP request (458 bytes) to UDP:192.168.1.253:65513 --->
CANCEL sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.131:5060;rport;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 CANCEL
Reason: Q.850;cause=0
Max-Forwards: 70
User-Agent: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:11] DEBUG[443000] cdr.c: Finalized CDR for Local/801@local-00000000;1 - start 1631799002.631290 answer 0.000000 end 1631799011.093825 dur 8.462 bill 1631799011.093 dispo FAILED
[Sep 16 13:30:11] DEBUG[443000] cdr.c: CDR for Local/801@local-00000000;1 is dialed and has no Party B; discarding
[Sep 16 13:30:11] DEBUG[443008] threadpool.c: Increasing threadpool pjsip/pool's size by 5
[Sep 16 13:30:11] DEBUG[443000] cdr.c: Finalized CDR for Local/801@local-00000000;2 - start 1631799002.631492 answer 0.000000 end 1631799011.093970 dur 8.462 bill 1631799011.093 dispo FAILED
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: No provider found, checking channel drivers for Local - 801@local
[Sep 16 13:30:11] DEBUG[442992] core_local.c: Checking if extension 801@local exists (devicestate)
[Sep 16 13:30:11] DEBUG[443000] cdr.c: Skipping CDR for Local/801@local-00000000;2 since we weren't answered
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: Changing state for Local/801@local - state 1 (Not in use)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Calling  Inv State: EARLY
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801(PJSIP/801-00000001)'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAC CANCEL transaction involved in this state change is 0x7f9610040658
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Calling
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is TX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is EARLY
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Nothing delayed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000001 TSX State: Calling  Inv State: EARLY
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Topology: Pending: (null topology)  Active:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] channel.c: Channel 0x7f9604005d20 'PJSIP/801-00000001' destroying
[Sep 16 13:30:11] DEBUG[443037] app_queue.c: Device 'Local/801@local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Sep 16 13:30:11] DEBUG[443011] stasis.c: Destroying topic. name: channel:1631799002.1, detail: 
[Sep 16 13:30:11] DEBUG[443011] stasis.c: Topic 'channel:1631799002.1': 0x7f9604002e90 destroyed
[Sep 16 13:30:11] DEBUG[443011] channel_internal_api.c:  <initializing>: MultistreamFormats: (nothing)
[Sep 16 13:30:11] DEBUG[443011] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  PJSIP/801-00000000
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  PJSIP/801-00000000 Response 500
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: 801: Destroying SIP session
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) DTLS stop
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610031bd0) ICE RTP transport deallocating
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: No provider found, checking channel drivers for PJSIP - 801
[Sep 16 13:30:11] DEBUG[443011] rtp_engine.c: Destroyed RTP instance '0x7f9610031bd0'
[Sep 16 13:30:11] DEBUG[443011] channel.c: Channel 0x7f961003e6f0 'PJSIP/801-00000000' destroying
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: Changing state for PJSIP/801 - state 2 (In use)
[Sep 16 13:30:11] DEBUG[443011] stasis.c: Destroying topic. name: channel:1631799002.0, detail: 
[Sep 16 13:30:11] DEBUG[443011] stasis.c: Topic 'channel:1631799002.0': 0x7f9610035d60 destroyed
[Sep 16 13:30:11] DEBUG[443037] app_queue.c: Device 'PJSIP/801' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Sep 16 13:30:11] DEBUG[443011] channel_internal_api.c:  <initializing>: MultistreamFormats: (nothing)
[Sep 16 13:30:11] DEBUG[443011] channel_internal_api.c:  Channel is being initialized or destroyed
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Removed 801 from pending_members
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: No provider found, checking channel drivers for PJSIP - 801
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '2' (In use)
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '2' (In use)
[Sep 16 13:30:11] DEBUG[442992] devicestate.c: Changing state for PJSIP/801 - state 1 (Not in use)
[Sep 16 13:30:11] DEBUG[443037] app_queue.c: Device 'PJSIP/801' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Removed 801 from pending_members
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '1' (Not in use)
[Sep 16 13:30:11] DEBUG[442995] app_queue.c: Extension '801@default' changed to state '1' (Not in use)
[Sep 16 13:30:11] DEBUG[443000] cdr.c: Finalized CDR for PJSIP/801-00000001 - start 1631799002.631101 answer 0.000000 end 1631799011.094136 dur 8.463 bill 1631799011.094 dispo FAILED
[Sep 16 13:30:11] DEBUG[443000] cdr.c: CDR for PJSIP/801-00000001 is dialed and has no Party B; discarding
[Sep 16 13:30:11] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP request (360 bytes) from UDP:192.168.1.253:65513 --->
ACK sip:200@dev SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:65513;branch=z9hG4bK-524287-1---ce49664d182753f9;rport
Max-Forwards: 70
To: <sip:200@dev>;tag=618cbcf9-ad55-40a3-ac1e-0068e80697da
From: <sip:801@dev>;tag=9fec2e48
Call-ID: 104679NDM3ZDRiZGNmM2E1ZjYwMDkxYWY2NmY2ZDJmZmY1Y2Q
CSeq: 2 ACK
Content-Length: 0


[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9610014778 for Request msg ACK/cseq=2 (rdata0x7f96b8000be8)
[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg ACK/cseq=2 (rdata0x7f96b8000be8)
[Sep 16 13:30:11] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP response (460 bytes) from UDP:192.168.1.253:65513 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.131:5060;rport=5060;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
Contact: <sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4>
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>;tag=2ecdf0ef
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 CANCEL
User-Agent: Bria 5 release 5.8.4 stamp 104679
Content-Length: 0


[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f961003b938 for Response msg 200/CANCEL/cseq=10527 (rdata0x7f96b8000be8)
[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/801-0000005d associated with dialog dlg0x7f961003b938
[Sep 16 13:30:11] VERBOSE[443010] res_pjsip_logger.c: <--- Received SIP response (409 bytes) from UDP:192.168.1.253:65513 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.1.131:5060;rport=5060;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>;tag=2ecdf0ef
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 INVITE
User-Agent: Bria 5 release 5.8.4 stamp 104679
Content-Length: 0


[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f961003b938 for Response msg 487/INVITE/cseq=10527 (rdata0x7f96b8000be8)
[Sep 16 13:30:11] DEBUG[443010] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/801-0000005d associated with dialog dlg0x7f961003b938
[Sep 16 13:30:11] DEBUG[443011] netsock2.c: Splitting '192.168.1.253' into...
[Sep 16 13:30:11] DEBUG[443011] netsock2.c: ...host '192.168.1.253' and port ''.
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '801' domain 'dev'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_endpoint_identifier_user.c: Identified by From username '801' domain 'dev'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: inv_session 0x7f96100196b8 has no ast session
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9610013d58)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9610013d58
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Confirmed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is RX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Session ended
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801 Method: CANCEL Status: 200
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801 TSX State: Completed  Inv State: EARLY
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Topology: Pending: (null topology)  Active:  <0:audio-0:audio:sendrecv (g722)>
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] VERBOSE[443011] res_pjsip_logger.c: <--- Transmitting SIP request (442 bytes) to UDP:192.168.1.253:65513 --->
ACK sip:801@192.168.1.253:65513;rinstance=e7e7ef201133e6d4 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.131:5060;rport;branch=z9hG4bKPjed38d935-d8c4-4a35-ad3a-94dc56a4b7b6
From: <sip:801@192.168.1.131>;tag=d649d74b-f1fd-473d-bef2-8c32291c0d5b
To: <sip:801@192.168.1.253;rinstance=e7e7ef201133e6d4>;tag=2ecdf0ef
Call-ID: ba8fcee4-eb4c-41b6-af69-e0ed06a8e947
CSeq: 10527 ACK
Max-Forwards: 70
User-Agent: Asterisk PBX 18.6.0
Content-Length:  0


[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801 Method: INVITE Status: 487
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801 Event: TSX_STATE  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801()'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: There is no transaction involved in this state change
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: 801: Source of transaction state change is RX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: 801: Received response
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801: Response is 487 Request Terminated
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  801: Status: 487
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  801: No channel
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  801 TSX State: Completed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The state change pertains to the endpoint '801()'
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f961004e2f8
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current transaction state is Completed
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The transaction state change event is RX_MSG
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  Disconnected
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c:  
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  801
[Sep 16 13:30:11] DEBUG[443011] chan_pjsip.c:  No channel
[Sep 16 13:30:11] DEBUG[443011] res_pjsip_session.c: 801: Destroying SIP session
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) DTLS stop
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) DTLS srtp - stopped timeout timer'
[Sep 16 13:30:11] DEBUG[443011] res_rtp_asterisk.c: (0x7f9610044410) ICE RTP transport deallocating
[Sep 16 13:30:11] DEBUG[443011] rtp_engine.c: Destroyed RTP instance '0x7f9610044410'
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: inv_session 0x7f96100196b8 has no ast session
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9610013d58
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The current transaction state is Terminated
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The transaction state change event is TIMER
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  Session ended
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: inv_session 0x7f961002c448 has no ast session
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f961004e2f8)
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The UAC CANCEL transaction involved in this state change is 0x7f9610040658
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The current transaction state is Terminated
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The transaction state change event is TIMER
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: The current inv state is DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  Session ended
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[Sep 16 13:30:16] DEBUG[443010] res_pjsip_session.c: