[2022-09-16 05:29:36] DEBUG[91442]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[2022-09-16 05:29:36] DEBUG[91122]: threadpool.c:1029 worker_thread_destroy: Destroying worker thread 53
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg INVITE/cseq=1 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-00000026 to use for Request msg INVITE/cseq=1 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 103.80.211.50:58652 does not match identify 'mobicom'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_authenticator_digest.c:471 digest_check_auth: Using default realm 'asterisk' on incoming auth '600'.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_authenticator_digest.c:358 verify: Realm: asterisk  Username: 600  Result: NOAUTH
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg ACK/cseq=1 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-00000026 to use for Request msg ACK/cseq=1 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 103.80.211.50:58652 does not match identify 'mobicom'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg INVITE/cseq=2 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-00000026 to use for Request msg INVITE/cseq=2 (rdata0x7f2c18014db8)
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 103.80.211.50:58652 does not match identify 'mobicom'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_authenticator_digest.c:471 digest_check_auth: Using default realm 'asterisk' on incoming auth '600'.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_authenticator_digest.c:261 check_nonce: Calculated nonce 1663306179/a9c5580897a09db79ff374bcd1703dd7. Actual nonce is 1663306179/a9c5580897a09db79ff374bcd1703dd7
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_authenticator_digest.c:358 verify: Realm: asterisk  Username: 600  Result: SUCCESS
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4235 session_on_rx_request:  (null session) Request: INVITE 
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4062 handle_new_invite_request:  Request: 
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-00000026 to use for Request msg INVITE/cseq=2 (rdata0x7f2c1808aed8)
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2974 chan_pjsip_session_begin:  600
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2978 chan_pjsip_session_begin:  Direct media no glare mitigation
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:3916 new_invite:  600
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:3996 new_invite:  600: Call (TCP:103.80.211.50:58652) to extension '80771559' sending 100 Trying
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4514 handle_outgoing_response:  600: Method is INVITE, Response is 100 Trying
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4533 handle_outgoing_response:  600
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4630 session_inv_on_state_changed:  600 Event: TSX_STATE  Inv State: INCOMING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '600()'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10016178)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4402 __print_debug_details: There is no transaction involved in this state change
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is INCOMING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4656 session_inv_on_state_changed: 600: Source of transaction state change is TX_MSG
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4704 session_inv_on_state_changed:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  600 TSX State: Proceeding  Inv State: INCOMING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '600()'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10016178)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f2c10016178
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Proceeding
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TX_MSG
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is INCOMING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4936 session_inv_on_tsx_state_changed:  Nothing delayed
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  600 TSX State: Proceeding  Inv State: INCOMING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4184 session_on_tsx_state:  Topology: Pending: (null topology)  Active: (null topology)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:769 handle_incoming_sdp:  600: Media count: 1
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:795 handle_incoming_sdp:  600: Processing stream 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:832 handle_incoming_sdp:  600: Using audio-0 for new stream name
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:876 handle_incoming_sdp:  600: Using new stream 0:audio-0:audio:sendrecv (nothing)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:495 ast_sip_session_media_state_add:  600 Adding position 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:541 ast_sip_session_media_state_add:  Creating new media session
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:584 ast_sip_session_media_state_add:  Setting media session as default for audio
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:589 ast_sip_session_media_state_add:  Done
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:929 handle_incoming_sdp:  600: Negotiating incoming SDP media stream 0:audio-0:audio:sendrecv (nothing) using audio SDP handler
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:1501 negotiate_incoming_sdp_stream:  600
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:257 create_rtp: Transport transport-tcp bound to 0.0.0.0: Using it for RTP media.
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:526 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7f2c100a3190'
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3872 rtp_allocate_transport: (0x7f2c100a3190) RTP allocated port 11350
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3902 rtp_allocate_transport: (0x7f2c100a3190) ICE creating session 0.0.0.0:11350 (11350)
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3781 ice_create: (0x7f2c100a3190) ICE create
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3558 rtp_add_candidates_to_ice: (0x7f2c100a3190) ICE add system candidates
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:1330 ast_rtp_ice_add_cand: (0x7f2c100a3190) ICE add candidate: 172.31.11.212:11350, 2130706431
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3664 rtp_add_candidates_to_ice: (0x7f2c100a3190) ICE request STUN TCP RTP candidate
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '18.220.96.228' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '18.220.96.228' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:1330 ast_rtp_ice_add_cand: (0x7f2c100a3190) ICE add candidate: 18.220.96.228:11350, 1694498815
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:543 ast_rtp_instance_new: RTP instance '0x7f2c100a3190' is setup and ready to go
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:919 ast_rtp_ice_stop: (0x7f2c100a3190) ICE stopped
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting 'ip-172-31-11-212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host 'ip-172-31-11-212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:8441 ast_rtp_prop_set: (0x7f2c100a3190) RTCP setup on RTP instance
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:453 set_incoming_call_offer_cap:  600
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:325 get_codecs:  600
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1310 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 9 based on m type on 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1310 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 0 based on m type on 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1310 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 8 based on m type on 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1310 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 3 based on m type on 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:412 get_codecs:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session/pjsip_session_caps.c:161 ast_sip_session_create_joint_call_cap: '600' Caps for incoming audio call with pref 'local' - remote: (opus|g722|ulaw|alaw|gsm) local: (ulaw|alaw) joint: (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 0 (0x561b4a959fa8) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 3 (0x561b4a959ff8) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 8 (0x561b4a95a188) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 9 (0x561b4a95a1d8) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 101 (0x7f2c10076288) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1274 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 106 (0x7f2c1004cad8) from 0x7f2bf85431a0 to 0x7f2bf85431a0
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 0 (0x561b4a959fa8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 3 (0x561b4a959ff8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 8 (0x561b4a95a188) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 9 (0x561b4a95a1d8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 101 (0x7f2c10076288) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1113 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 106 (0x7f2c1004cad8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 0 (0x561b4a959fa8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 3 (0x561b4a959ff8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 8 (0x561b4a95a188) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 9 (0x561b4a95a1d8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 101 (0x7f2c10076288) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:1196 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 106 (0x7f2c1004cad8) from 0x7f2bf85431a0 to 0x7f2c100a3368
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:488 set_incoming_call_offer_cap:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:1591 negotiate_incoming_sdp_stream:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:948 handle_incoming_sdp:  600: Media stream 0:audio-0:audio:sendrecv (ulaw|alaw) handled by audio
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:955 handle_incoming_sdp:  600: Done with stream 0:audio-0:audio:sendrecv (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:960 handle_incoming_sdp:  600: Handled? yes
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5100 create_local_sdp:  600
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5137 create_local_sdp:  600: Processing streams
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5143 create_local_sdp:  600: Processing stream 0:audio-0:audio:sendrecv (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:495 ast_sip_session_media_state_add:  600 Adding position 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:503 ast_sip_session_media_state_add:  Using existing media_session
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4976 add_sdp_streams:  600 Stream: 0:audio-0:audio:sendrecv (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:1746 create_outgoing_sdp_stream:  600 Type: audio 0:audio-0:audio:sendrecv (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:8340 ast_rtp_prop_set: (0x7f2c100a3190) RTCP ignoring duplicate property
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:2031 create_outgoing_sdp_stream:  RC: 1
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4985 add_sdp_streams:  Had handler
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5175 create_local_sdp:  600: Stream 0:audio-0:audio:sendrecv (ulaw|alaw) added
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5188 create_local_sdp:  600: Done with 0:audio-0:audio:sendrecv (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5194 create_local_sdp:  600: Adding bundle groups (if available)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5200 create_local_sdp:  600: Copying connection details
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5203 create_local_sdp:  600: Processing media 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5221 create_local_sdp:  600: Media 0 reset
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5244 create_local_sdp:  600
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4413 handle_incoming_request:  600: Method is INVITE
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3033 chan_pjsip_incoming_request:  600
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:547 chan_pjsip_new:  600
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:698 ast_channel_nativeformats_set:  <initializing>: Formats: (none)
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:710 ast_channel_nativeformats_set:  Channel is being initialized or destroyed
[2022-09-16 05:29:39] DEBUG[91148]: stasis.c:580 stasis_topic_create_with_detail: Creating topic. name: channel:RaspberryPiPBX-1663306179.10, detail: 
[2022-09-16 05:29:39] DEBUG[91148]: stasis.c:614 stasis_topic_create_with_detail: Topic 'channel:RaspberryPiPBX-1663306179.10': 0x7f2c1005eec0 created
[2022-09-16 05:29:39] DEBUG[91122]: threadpool.c:536 grow: Increasing threadpool stasis/pool's size by 1
[2022-09-16 05:29:39] DEBUG[91148]: channel.c:949 __ast_channel_alloc_ap: Channel 0x7f2c1002b8d0 'PJSIP/600-0000000a' allocated
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:522 compatible_formats_exist:  Topology:  <0:audio-0:audio:sendrecv (ulaw|alaw)> Formats: (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:535 compatible_formats_exist:  Compatible? yes
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:698 ast_channel_nativeformats_set:  PJSIP/600-0000000a: MultistreamFormats: (ulaw|alaw)
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:720 ast_channel_nativeformats_set:  Set native formats but not topology
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:1613 ast_channel_set_stream_topology:  PJSIP/600-0000000a:  <0:audio-0:audio:sendrecv (ulaw|alaw)>
[2022-09-16 05:29:39] DEBUG[91148]: channel_internal_api.c:1634 ast_channel_set_stream_topology:  Used provided topology
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:667 chan_pjsip_new:  
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3083 chan_pjsip_incoming_request:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3139 pbx_start_incoming_request:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3165 pbx_start_incoming_request: Started PBX on new PJSIP channel PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3167 pbx_start_incoming_request:  RC: 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4423 handle_incoming_request:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4045 new_invite:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4136 handle_new_invite_request:  Request:  Session: PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4254 session_on_rx_request:  (null session) Handled request INVITE  ? yes
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx_variables.c:380 ast_str_retrieve_variable: Result of 'EXTEN' is '80771559'
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx.c:2936 pbx_extension_helper: Launching 'NoOp'
    -- Executing [80771559@from-extensions:1] NoOp("PJSIP/600-0000000a", "80771559") in new stack
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx_variables.c:380 ast_str_retrieve_variable: Result of 'EXTEN' is '80771559'
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx.c:2936 pbx_extension_helper: Launching 'Goto'
    -- Executing [80771559@from-extensions:2] Goto("PJSIP/600-0000000a", "outgoing,80771559,1") in new stack
    -- Goto (outgoing,80771559,1)
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx.c:2936 pbx_extension_helper: Launching 'Set'
    -- Executing [80771559@outgoing:1] Set("PJSIP/600-0000000a", "CALLERID(num)=+97675097474200") in new stack
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx_variables.c:380 ast_str_retrieve_variable: Result of 'EXTEN' is '80771559'
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: pbx.c:2936 pbx_extension_helper: Launching 'Dial'
    -- Executing [80771559@outgoing:2] Dial("PJSIP/600-0000000a", "PJSIP/80771559@mobicom") in new stack
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: app_dial.c:2376 dial_exec_full:  PJSIP/600-0000000a: Data: PJSIP/80771559@mobicom
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:2692 chan_pjsip_request_with_stream_topology:  80771559@mobicom Topology:  <0:audio-0:audio:sendrecv (ulaw|alaw)>
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2591 request:  80771559@mobicom
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:3330 ast_sip_session_create_outgoing:  mobicom 80771559 Topology:  <0:audio-0:audio:sendrecv (ulaw|alaw)>
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2974 chan_pjsip_session_begin:  mobicom
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2978 chan_pjsip_session_begin:  Direct media no glare mitigation
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session/pjsip_session_caps.c:146 ast_sip_session_create_joint_call_stream: 'mobicom' Caps for outgoing audio call with pref 'remote_merge' - remote: (ulaw|alaw) local: (ulaw) joint: (ulaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:3446 ast_sip_session_create_outgoing:  
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2684 request:  
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:547 chan_pjsip_new:  mobicom
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:698 ast_channel_nativeformats_set:  <initializing>: Formats: (none)
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:710 ast_channel_nativeformats_set:  Channel is being initialized or destroyed
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: stasis.c:580 stasis_topic_create_with_detail: Creating topic. name: channel:RaspberryPiPBX-1663306179.11, detail: 
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: stasis.c:614 stasis_topic_create_with_detail: Topic 'channel:RaspberryPiPBX-1663306179.11': 0x7f2c10040a10 created
[2022-09-16 05:29:39] DEBUG[91122]: threadpool.c:536 grow: Increasing threadpool stasis/pool's size by 1
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel.c:949 __ast_channel_alloc_ap: Channel 0x7f2c1008a280 'PJSIP/mobicom-0000000b' allocated
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:522 compatible_formats_exist:  Topology:  <0:audio-0:audio:sendrecv (ulaw)> Formats: (ulaw)
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:535 compatible_formats_exist:  Compatible? yes
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:698 ast_channel_nativeformats_set:  PJSIP/mobicom-0000000b: MultistreamFormats: (ulaw)
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:720 ast_channel_nativeformats_set:  Set native formats but not topology
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:1613 ast_channel_set_stream_topology:  PJSIP/mobicom-0000000b:  <0:audio-0:audio:sendrecv (ulaw)>
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel_internal_api.c:1634 ast_channel_set_stream_topology:  Used provided topology
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:667 chan_pjsip_new:  
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:2712 chan_pjsip_request_with_stream_topology:  Channel: PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:2409 chan_pjsip_call:  PJSIP/mobicom-0000000b Topology:  <0:audio-0:audio:sendrecv (ulaw)>
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2385 call:  PJSIP/mobicom-0000000b Topology:  <0:audio-0:audio:sendrecv (ulaw)>
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:2851 ast_sip_session_create_invite:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5100 create_local_sdp:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5137 create_local_sdp:  PJSIP/mobicom-0000000b: Processing streams
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5143 create_local_sdp:  PJSIP/mobicom-0000000b: Processing stream 0:audio-0:audio:sendrecv (ulaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:495 ast_sip_session_media_state_add:  PJSIP/mobicom-0000000b Adding position 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:541 ast_sip_session_media_state_add:  Creating new media session
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:584 ast_sip_session_media_state_add:  Setting media session as default for audio
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:589 ast_sip_session_media_state_add:  Done
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4976 add_sdp_streams:  PJSIP/mobicom-0000000b Stream: 0:audio-0:audio:sendrecv (ulaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:1746 create_outgoing_sdp_stream:  PJSIP/mobicom-0000000b Type: audio 0:audio-0:audio:sendrecv (ulaw)
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:257 create_rtp: Transport transport-tcp bound to 0.0.0.0: Using it for RTP media.
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:526 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7f2c10022920'
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3872 rtp_allocate_transport: (0x7f2c10022920) RTP allocated port 15658
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3902 rtp_allocate_transport: (0x7f2c10022920) ICE creating session 0.0.0.0:15658 (15658)
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3781 ice_create: (0x7f2c10022920) ICE create
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3558 rtp_add_candidates_to_ice: (0x7f2c10022920) ICE add system candidates
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:1330 ast_rtp_ice_add_cand: (0x7f2c10022920) ICE add candidate: 172.31.11.212:15658, 2130706431
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:3664 rtp_add_candidates_to_ice: (0x7f2c10022920) ICE request STUN TCP RTP candidate
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:2419 chan_pjsip_call:  'call' task pushed
    -- Called PJSIP/80771559@mobicom
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: app_dial.c:1269 wait_for_answer:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel.c:5821 set_format: Channel PJSIP/mobicom-0000000b setting read format path: ulaw -> ulaw
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel.c:5821 set_format: Channel PJSIP/600-0000000a setting write format path: ulaw -> ulaw
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel.c:5821 set_format: Channel PJSIP/600-0000000a setting read format path: ulaw -> ulaw
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: channel.c:5821 set_format: Channel PJSIP/mobicom-0000000b setting write format path: ulaw -> ulaw
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '18.220.96.228' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '18.220.96.228' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:1330 ast_rtp_ice_add_cand: (0x7f2c10022920) ICE add candidate: 18.220.96.228:15658, 1694498815
[2022-09-16 05:29:39] DEBUG[91148]: rtp_engine.c:543 ast_rtp_instance_new: RTP instance '0x7f2c10022920' is setup and ready to go
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:919 ast_rtp_ice_stop: (0x7f2c10022920) ICE stopped
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting 'ip-172-31-11-212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host 'ip-172-31-11-212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_rtp_asterisk.c:8441 ast_rtp_prop_set: (0x7f2c10022920) RTCP setup on RTP instance
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_sdp_rtp.c:2031 create_outgoing_sdp_stream:  RC: 1
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5006 add_sdp_streams:  Handled
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5175 create_local_sdp:  PJSIP/mobicom-0000000b: Stream 0:audio-0:audio:sendrecv (ulaw) added
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5188 create_local_sdp:  PJSIP/mobicom-0000000b: Done with 0:audio-0:audio:sendrecv (ulaw)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5194 create_local_sdp:  PJSIP/mobicom-0000000b: Adding bundle groups (if available)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5200 create_local_sdp:  PJSIP/mobicom-0000000b: Copying connection details
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5203 create_local_sdp:  PJSIP/mobicom-0000000b: Processing media 0
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5221 create_local_sdp:  PJSIP/mobicom-0000000b: Media 0 reset
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5244 create_local_sdp:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:2876 ast_sip_session_create_invite:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4496 handle_outgoing_request:  PJSIP/mobicom-0000000b: Method is INVITE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4506 handle_outgoing_request:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip/pjsip_resolver.c:475 sip_resolve: Performing SIP DNS resolution of target '202.131.248.20'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip/pjsip_resolver.c:502 sip_resolve: Transport type for target '202.131.248.20' is 'TCP transport'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip/pjsip_resolver.c:523 sip_resolve: Target '202.131.248.20' is an IP address, skipping resolution
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip/pjsip_message_filter.c:298 filter_on_tx_message: Re-wrote Contact URI host/port to 172.31.11.212:5060 (this may be re-written again later)
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '202.131.248.20' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '202.131.248.20' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '172.31.11.212' into...
[2022-09-16 05:29:39] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '172.31.11.212' and port ''.
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:5515 session_outgoing_nat_hook: PJSIP/mobicom-0000000b: Setting external media address to 18.220.96.228
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4630 session_inv_on_state_changed:  PJSIP/mobicom-0000000b Event: TSX_STATE  Inv State: CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint 'mobicom(PJSIP/mobicom-0000000b)'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10022ec8)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4402 __print_debug_details: There is no transaction involved in this state change
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4656 session_inv_on_state_changed: PJSIP/mobicom-0000000b: Source of transaction state change is TX_MSG
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4704 session_inv_on_state_changed:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  PJSIP/mobicom-0000000b TSX State: Calling  Inv State: CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint 'mobicom(PJSIP/mobicom-0000000b)'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10022ec8)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7f2c10022ec8
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Calling
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TX_MSG
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4936 session_inv_on_tsx_state_changed:  Nothing delayed
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  PJSIP/mobicom-0000000b TSX State: Calling  Inv State: CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4184 session_on_tsx_state:  Topology: Pending:  <0:audio-0:audio:sendrecv (ulaw)>  Active: (null topology)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:2402 call:  RC: 0
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:1632 chan_pjsip_indicate:  PJSIP/600-0000000a: Indicated Connected line update
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:1863 chan_pjsip_indicate:  PJSIP/600-0000000a
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f2c10071b28 for Response msg 100/INVITE/cseq=31252 (rdata0x7f2c10092128)
[2022-09-16 05:29:39] DEBUG[91147]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/outsess/mobicom-00000076 associated with dialog dlg0x7f2c10071b28
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4203 session_on_rx_response:  PJSIP/mobicom-0000000b Method: INVITE Status: 100
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4206 session_on_rx_response:  
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  PJSIP/mobicom-0000000b TSX State: Proceeding  Inv State: CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint 'mobicom(PJSIP/mobicom-0000000b)'
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10022ec8)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7f2c10022ec8
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Proceeding
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is RX_MSG
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4465 handle_incoming_response:  PJSIP/mobicom-0000000b: Response is 100 Trying
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3210 chan_pjsip_incoming_response:  PJSIP/mobicom-0000000b: Status: 100
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3255 chan_pjsip_incoming_response:  PJSIP/mobicom-0000000b: Not queueing anything
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3259 chan_pjsip_incoming_response:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3182 chan_pjsip_incoming_response_update_cause:  PJSIP/mobicom-0000000b: Status: 100
[2022-09-16 05:29:39] DEBUG[91148]: chan_pjsip.c:3203 chan_pjsip_incoming_response_update_cause:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4477 handle_incoming_response:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4936 session_inv_on_tsx_state_changed:  Nothing delayed
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  PJSIP/mobicom-0000000b TSX State: Proceeding  Inv State: CALLING
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4184 session_on_tsx_state:  Topology: Pending:  <0:audio-0:audio:sendrecv (ulaw)>  Active: (null topology)
[2022-09-16 05:29:39] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:1632 chan_pjsip_indicate:  PJSIP/600-0000000a: Indicated Private Cause Code
[2022-09-16 05:29:39] DEBUG[91455][C-00000006]: chan_pjsip.c:1863 chan_pjsip_indicate:  PJSIP/600-0000000a
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f2c10071b28 for Response msg 403/INVITE/cseq=31252 (rdata0x7f2c10092128)
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/outsess/mobicom-00000076 associated with dialog dlg0x7f2c10071b28
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '202.131.248.20' into...
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '202.131.248.20' and port ''.
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4203 session_on_rx_response:  PJSIP/mobicom-0000000b Method: INVITE Status: 403
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4206 session_on_rx_response:  
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4630 session_inv_on_state_changed:  PJSIP/mobicom-0000000b Event: TSX_STATE  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint 'mobicom(PJSIP/mobicom-0000000b)'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10022ec8)
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4402 __print_debug_details: There is no transaction involved in this state change
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4656 session_inv_on_state_changed: PJSIP/mobicom-0000000b: Source of transaction state change is RX_MSG
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4598 handle_incoming_before_media: PJSIP/mobicom-0000000b: Received response
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4465 handle_incoming_response:  PJSIP/mobicom-0000000b: Response is 403 Forbidden
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:3182 chan_pjsip_incoming_response_update_cause:  PJSIP/mobicom-0000000b: Status: 403
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:3203 chan_pjsip_incoming_response_update_cause:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4477 handle_incoming_response:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4704 session_inv_on_state_changed:  
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  PJSIP/mobicom-0000000b TSX State: Completed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint 'mobicom(PJSIP/mobicom-0000000b)'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10022ec8)
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7f2c10022ec8
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Completed
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is RX_MSG
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  Disconnected
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2995 chan_pjsip_session_end:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:3012 chan_pjsip_session_end:  
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4380 __print_debug_details: inv_session 0x7f2c10060bc8 has no ast session
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4390 __print_debug_details: The inv session does NOT have an invite_tsx
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4393 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7f2c10022ec8
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Terminated
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TIMER
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4760 session_inv_on_tsx_state_changed:  Session ended
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:1632 chan_pjsip_indicate:  PJSIP/600-0000000a: Indicated Private Cause Code
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:1863 chan_pjsip_indicate:  PJSIP/600-0000000a
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2549 ast_hangup: Channel 0x7f2c1008a280 'PJSIP/mobicom-0000000b' hanging up.  Refs: 2
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:2542 chan_pjsip_hangup:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2509 hangup:  PJSIP/mobicom-0000000b
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:3456 ast_sip_session_terminate:  PJSIP/mobicom-0000000b Response 403
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:3528 ast_sip_session_terminate:  
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:2912 session_destructor: mobicom: Destroying SIP session
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c10022920) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c10022920) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2276 ast_rtp_dtls_stop: (0x7f2c10022920) DTLS stop
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c10022920) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c10022920) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:3982 rtp_deallocate_transport: (0x7f2c10022920) ICE RTP transport deallocating
[2022-09-16 05:29:40] DEBUG[91148]: rtp_engine.c:455 instance_destructor: Destroyed RTP instance '0x7f2c10022920'
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2533 hangup:  
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:2560 chan_pjsip_hangup:  Cause: 403
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2196 ast_channel_destructor: Channel 0x7f2c1008a280 'PJSIP/mobicom-0000000b' destroying
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: stasis.c:442 topic_dtor: Destroying topic. name: channel:RaspberryPiPBX-1663306179.11, detail: 
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: stasis.c:451 topic_dtor: Topic 'channel:RaspberryPiPBX-1663306179.11': 0x7f2c10040a10 destroyed
[2022-09-16 05:29:40] DEBUG[91134]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for PJSIP - mobicom
[2022-09-16 05:29:40] DEBUG[91134]: devicestate.c:466 do_state_change: Changing state for PJSIP/mobicom - state 1 (Not in use)
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel_internal_api.c:698 ast_channel_nativeformats_set:  <initializing>: MultistreamFormats: (nothing)
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel_internal_api.c:710 ast_channel_nativeformats_set:  Channel is being initialized or destroyed
  == Everyone is busy/congested at this time (1:0/0/1)
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: app_dial.c:1328 wait_for_answer:  PJSIP/600-0000000a: No outging channels available
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: app_dial.c:3468 dial_exec_full: Exiting with DIALSTATUS=CHANUNAVAIL.
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: app_dial.c:3499 dial_exec_full:  PJSIP/600-0000000a: Done
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: pbx.c:2936 pbx_extension_helper: Launching 'Hangup'
    -- Executing [80771559@outgoing:3] Hangup("PJSIP/600-0000000a", "") in new stack
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2459 ast_softhangup_nolock: Soft-Hanging (0x20) up channel 'PJSIP/600-0000000a'
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: pbx.c:4435 __ast_pbx_run: Spawn extension (outgoing,80771559,3) exited non-zero on 'PJSIP/600-0000000a'
  == Spawn extension (outgoing, 80771559, 3) exited non-zero on 'PJSIP/600-0000000a'
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2459 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'PJSIP/600-0000000a'
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2549 ast_hangup: Channel 0x7f2c1002b8d0 'PJSIP/600-0000000a' hanging up.  Refs: 2
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:2542 chan_pjsip_hangup:  PJSIP/600-0000000a
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2509 hangup:  PJSIP/600-0000000a
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:3456 ast_sip_session_terminate:  PJSIP/600-0000000a Response 403
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4514 handle_outgoing_response:  PJSIP/600-0000000a: Method is INVITE, Response is 403 Forbidden
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: chan_pjsip.c:2560 chan_pjsip_hangup:  Cause: 403
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4533 handle_outgoing_response:  PJSIP/600-0000000a
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4630 session_inv_on_state_changed:  PJSIP/600-0000000a Event: TSX_STATE  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '600(PJSIP/600-0000000a)'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10016178)
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4402 __print_debug_details: There is no transaction involved in this state change
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4656 session_inv_on_state_changed: PJSIP/600-0000000a: Source of transaction state change is TX_MSG
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4704 session_inv_on_state_changed:  
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  PJSIP/600-0000000a TSX State: Completed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '600(PJSIP/600-0000000a)'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10016178)
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f2c10016178
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Completed
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TX_MSG
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  Disconnected
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:3528 ast_sip_session_terminate:  
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2533 hangup:  
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2995 chan_pjsip_session_end:  600
[2022-09-16 05:29:40] DEBUG[91148]: chan_pjsip.c:2998 chan_pjsip_session_end:  No channel
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:2912 session_destructor: 600: Destroying SIP session
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c100a3190) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c100a3190) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2276 ast_rtp_dtls_stop: (0x7f2c100a3190) DTLS stop
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c100a3190) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:2874 dtls_srtp_stop_timeout_timer: (0x7f2c100a3190) DTLS srtp - stopped timeout timer'
[2022-09-16 05:29:40] DEBUG[91148]: res_rtp_asterisk.c:3982 rtp_deallocate_transport: (0x7f2c100a3190) ICE RTP transport deallocating
[2022-09-16 05:29:40] DEBUG[91148]: rtp_engine.c:455 instance_destructor: Destroyed RTP instance '0x7f2c100a3190'
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel.c:2196 ast_channel_destructor: Channel 0x7f2c1002b8d0 'PJSIP/600-0000000a' destroying
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: stasis.c:442 topic_dtor: Destroying topic. name: channel:RaspberryPiPBX-1663306179.10, detail: 
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: stasis.c:451 topic_dtor: Topic 'channel:RaspberryPiPBX-1663306179.10': 0x7f2c1005eec0 destroyed
[2022-09-16 05:29:40] DEBUG[91134]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for PJSIP - 600
[2022-09-16 05:29:40] DEBUG[91134]: devicestate.c:466 do_state_change: Changing state for PJSIP/600 - state 1 (Not in use)
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel_internal_api.c:698 ast_channel_nativeformats_set:  <initializing>: MultistreamFormats: (nothing)
[2022-09-16 05:29:40] DEBUG[91455][C-00000006]: channel_internal_api.c:710 ast_channel_nativeformats_set:  Channel is being initialized or destroyed
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f2c1003be18 for Request msg ACK/cseq=2 (rdata0x7f2c18014db8)
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-00000026 to use for Request msg ACK/cseq=2 (rdata0x7f2c18014db8)
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '103.80.211.50' into...
[2022-09-16 05:29:40] DEBUG[91148]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '103.80.211.50' and port ''.
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 103.80.211.50:58652 does not match identify 'mobicom'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '600' domain 'asterisk.monosolution.co'
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4380 __print_debug_details: inv_session 0x7f2c1002e1f8 has no ast session
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f2c10016178)
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f2c10016178
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Confirmed
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is RX_MSG
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4760 session_inv_on_tsx_state_changed:  Session ended
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91148]: res_pjsip_session.c:4189 session_on_tsx_state:  
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4380 __print_debug_details: inv_session 0x7f2c1002e1f8 has no ast session
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4390 __print_debug_details: The inv session does NOT have an invite_tsx
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f2c10016178
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Terminated
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TIMER
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4760 session_inv_on_tsx_state_changed:  Session ended
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2022-09-16 05:29:40] DEBUG[91147]: res_pjsip_session.c:4189 session_on_tsx_state: