Via: SIP/2.0/UDP 10.0.1.8:5060;rport;branch=z9hG4bK1587609963
From: "102" <sip:102@10.0.1.8>;tag=1868506277
To: <sip:101@10.0.1.24:5060>
Call-ID: 284249919@10.0.1.8
User-Agent: YATE/5.5.0
Contact: <sip:102@10.0.1.8:5060>
Allow: ACK, INVITE, BYE, CANCEL, MESSAGE, OPTIONS, INFO
CSeq: 20 INVITE
Authorization: Digest username="102", realm="asterisk", nonce="1630060591/c5f76b0113e45c08864ef351f93eca1b", uri="sip:101@10.0.1.24:5060", response="01213f3ef1beca0382092cb7b85e7830", algorithm=MD5, opaque="2592fffd3012db1d", qop=auth, nc=0000000a, cnonce="c33e2884640b9a6c96e99d127bf6bc0b"
Content-Type: application/sdp
Content-Length: 280
v=0
o=yate 1630064195 1630064195 IN IP4 10.0.1.8
s=SIP Call
c=IN IP4 10.0.1.8
t=0 0
m=audio 9654 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
m=video 9856 RTP/AVP 96
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=4D001F; packetization-mode=1
[2021-08-27 12:36:31] DEBUG[2278] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=20 (rdata0x7f309c000f88)
[2021-08-27 12:36:31] DEBUG[2278] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000039 to use for Request msg INVITE/cseq=20 (rdata0x7f309c000f88)
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.8' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.8' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '102' domain '10.0.1.8'
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_endpoint_identifier_user.c: Identified by From username '102' domain '10.0.1.8'
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '102-auth'.
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_authenticator_digest.c: Calculated nonce 1630060591/c5f76b0113e45c08864ef351f93eca1b. Actual nonce is 1630060591/c5f76b0113e45c08864ef351f93eca1b
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.24' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.24' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.8' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.8' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000039 to use for Request msg INVITE/cseq=20 (rdata0x7f309c030d68)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102
[2021-08-27 12:36:31] VERBOSE[2279] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '10.0.1.24'
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Call (UDP:10.0.1.8:5060) to extension '101' sending 100 Trying
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Method is INVITE, Response is 100 Trying
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.8' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.8' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation
[2021-08-27 12:36:31] VERBOSE[2279] res_pjsip_logger.c: <--- Transmitting SIP response (276 bytes) to UDP:10.0.1.8:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.0.1.8:5060;rport=5060;received=10.0.1.8;branch=z9hG4bK1587609963
Call-ID: 284249919@10.0.1.8
From: "102" <sip:102@10.0.1.8>;tag=1868506277
To: <sip:101@10.0.1.24>
CSeq: 20 INVITE
Server: Asterisk PBX 16.15.1
Content-Length: 0
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The state change pertains to the endpoint '102()'
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f30a00859a8)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: There is no transaction involved in this state change
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The current inv state is INCOMING
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Source of transaction state change is TX_MSG
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The state change pertains to the endpoint '102()'
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f30a00859a8)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f30a00859a8
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The current transaction state is Proceeding
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The transaction state change event is TX_MSG
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: The current inv state is INCOMING
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Media count: 2
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing stream 0
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Using audio-0 for new stream name
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Using new stream 0:audio-0:audio:sendrecv (nothing)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102 Adding position 0
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Creating new media session
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Setting media session as default for audio
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Done
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Negotiating incoming SDP media stream 0:audio-0:audio:sendrecv (nothing) using audio SDP handler
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.8' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.8' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f30a005c340'
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) RTP allocated port 18916
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE creating session [::]:18916 (18916)
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE create
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE add system candidates
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.24' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.24' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE add candidate: 10.0.1.24:18916, 2130706431
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting 'fe80::20d:b9ff:fe40:2194' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host 'fe80::20d:b9ff:fe40:2194' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE add candidate: [fe80::20d:b9ff:fe40:2194]:18916, 2130706431
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: RTP instance '0x7f30a005c340' is setup and ready to go
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) ICE stopped
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Audio TOS bits 184
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Audio CoS mark 5
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting 'pbx.tm.local' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host 'pbx.tm.local' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] acl.c: Multiple addresses. Using the first only
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) RTCP setup on RTP instance
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3061ed2f10
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x7f30a004d698) from 0x7f3061ed2f10 to 0x7f3061ed2f10
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x7f30a005b248) from 0x7f3061ed2f10 to 0x7f3061ed2f10
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying rx payload mapping 0 (0x7f30a004d698) from 0x7f3061ed2f10 to 0x7f30a005c518
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying rx payload mapping 101 (0x7f30a005b248) from 0x7f3061ed2f10 to 0x7f30a005c518
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying tx payload mapping 0 (0x7f30a004d698) from 0x7f3061ed2f10 to 0x7f30a005c518
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying tx payload mapping 101 (0x7f30a005b248) from 0x7f3061ed2f10 to 0x7f30a005c518
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Media stream 0:audio-0:audio:sendrecv (ulaw) handled by audio
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Done with stream 0:audio-0:audio:sendrecv (ulaw)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing stream 1
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Using video-1 for new stream name
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Using new stream 1:video-1:video:sendrecv (nothing)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102 Adding position 1
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Creating new media session
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Setting media session as default for video
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Done
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Negotiating incoming SDP media stream 1:video-1:video:sendrecv (nothing) using video SDP handler
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.8' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.8' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f30a0085200'
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) RTP allocated port 11798
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE creating session [::]:11798 (11798)
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE create
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE add system candidates
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting '10.0.1.24' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host '10.0.1.24' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE add candidate: 10.0.1.24:11798, 2130706431
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting 'fe80::20d:b9ff:fe40:2194' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host 'fe80::20d:b9ff:fe40:2194' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE add candidate: [fe80::20d:b9ff:fe40:2194]:11798, 2130706431
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: RTP instance '0x7f30a0085200' is setup and ready to go
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) ICE stopped
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Video TOS bits 136
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Video TOS bits 136 in TCLASS field.
[2021-08-27 12:36:31] VERBOSE[2279] netsock2.c: Using SIP RTP Video CoS mark 4
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: Splitting 'pbx.tm.local' into...
[2021-08-27 12:36:31] DEBUG[2279] netsock2.c: ...host 'pbx.tm.local' and port ''.
[2021-08-27 12:36:31] DEBUG[2279] acl.c: Multiple addresses. Using the first only
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) RTCP setup on RTP instance
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Crossover copying tx to rx payload mapping 96 (0x7f30a007df68) from 0x7f3061ed2e30 to 0x7f3061ed2e30
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying rx payload mapping 96 (0x7f30a007df68) from 0x7f3061ed2e30 to 0x7f30a00853d8
[2021-08-27 12:36:31] DEBUG[2279] rtp_engine.c: Copying tx payload mapping 96 (0x7f30a007df68) from 0x7f3061ed2e30 to 0x7f30a00853d8
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Media stream 1:video-1:video:sendrecv (h264) handled by video
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Done with stream 1:video-1:video:sendrecv (h264)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Handled? yes
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing streams
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing stream 0:audio-0:audio:sendrecv (ulaw)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102 Adding position 0
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Using existing media_session
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a005c340) RTCP ignoring duplicate property
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Stream 0:audio-0:audio:sendrecv (ulaw) added
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Done with 0:audio-0:audio:sendrecv (ulaw)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing stream 1:video-1:video:sendrecv (h264)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102 Adding position 1
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: Using existing media_session
[2021-08-27 12:36:31] DEBUG[2279] res_rtp_asterisk.c: (0x7f30a0085200) RTCP ignoring duplicate property
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Stream 1:video-1:video:sendrecv (h264) added
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Done with 1:video-1:video:sendrecv (h264)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Adding bundle groups (if available)
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Copying connection details
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing media 0
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Media 0 reset
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Processing media 1
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Media 1 has good existing connection info
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: 102: Method is INVITE
[2021-08-27 12:36:31] DEBUG[2279] chan_pjsip.c: 102
[2021-08-27 12:36:31] DEBUG[2279] stasis.c: Creating topic. name: channel:1630060591.15, detail:
[2021-08-27 12:36:31] DEBUG[2279] stasis.c: Topic 'channel:1630060591.15': 0x7f30a00809c0 created
[2021-08-27 12:36:31] DEBUG[2279] stasis.c: Creating topic. name: cache:27/channel:1630060591.15, detail:
[2021-08-27 12:36:31] DEBUG[2279] stasis.c: Topic 'cache:27/channel:1630060591.15': 0x7f30a005a090 created
[2021-08-27 12:36:31] DEBUG[2279] channel.c: Channel 0x7f30a0057ea0 'PJSIP/102-0000000f' allocated
[2021-08-27 12:36:31] DEBUG[2279] chan_pjsip.c: PJSIP/102-0000000f
[2021-08-27 12:36:31] DEBUG[2279] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/102-0000000f
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: PJSIP/102-0000000f
[2021-08-27 12:36:31] DEBUG[2279] res_pjsip_session.c: PJSIP/102-0000000f
[2021-08-27 12:36:31] DEBUG[2284] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: none
Uniqueid: none
Variable: SIPDOMAIN
Value: 10.0.1.24