[Nov 16 09:06:52] Asterisk certified/13.18-cert1-rc2 built by root @ ast-directmedia-2 on a x86_64 running Linux on 2017-11-15 18:08:52 UTC [Nov 16 09:06:52] DEBUG[20195] config.c: Parsing /etc/asterisk/logger.conf [Nov 16 09:06:52] VERBOSE[20195] config.c: Parsing '/etc/asterisk/logger.conf': Found [Nov 16 09:06:52] VERBOSE[20195] logger.c: Asterisk Queue Logger restarted [Nov 16 09:06:52] VERBOSE[20195] asterisk.c: Remote UNIX connection disconnected [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=29069 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003e to use for Request msg REGISTER/cseq=29069 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-one' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-three' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Identify checks by IP address failed to find match: '10.24.20.43:5060' did not match any identify section rules [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Identified by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1603-auth'. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] SECURITY[13753] res_security_log.c: SecurityEvent="ChallengeSent",EventTV="2017-11-16T09:06:56.939-0600",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="1603",SessionID="4DmjqdrGfS7nXvXYyPQuPzeF1t-2blfK",LocalAddress="IPV4/UDP/10.27.69.53/5060",RemoteAddress="IPV4/UDP/10.24.20.43/5060",Challenge="" [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=29070 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003e to use for Request msg REGISTER/cseq=29070 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-one' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-three' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Identify checks by IP address failed to find match: '10.24.20.43:5060' did not match any identify section rules [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Identified by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1603-auth'. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Calculated nonce 1510844816/0c0fb60890e2d2a8fecca25c85cbd692. Actual nonce is 1510844816/0c0fb60890e2d2a8fecca25c85cbd692 [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_registrar.c: Matched id '1603' to aor '1603' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_registrar.c: Matched aor '1603' by To username [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Nov 16 09:06:56] DEBUG[20181] res_pjsip_registrar.c: Refreshed contact 'sip:1603@10.24.20.43:5060;ob' on AOR '1603' with new expiration of 300 seconds [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Nov 16 09:06:56] DEBUG[20181] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Nov 16 09:06:56] SECURITY[13753] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2017-11-16T09:06:56.942-0600",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="1603",SessionID="4DmjqdrGfS7nXvXYyPQuPzeF1t-2blfK",LocalAddress="IPV4/UDP/10.27.69.53/5060",RemoteAddress="IPV4/UDP/10.24.20.43/5060",UsingPassword="1" [Nov 16 09:06:56] DEBUG[13656] threadpool.c: Increasing threadpool Sorcery's size by 1 [Nov 16 09:06:56] DEBUG[20196] config.c: extract uint from [3] in [0, 4294967295] gives [3](0) [Nov 16 09:06:56] DEBUG[20196] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Nov 16 09:06:56] DEBUG[20196] config.c: extract uint from [2] in [0, 4294967295] gives [2](0) [Nov 16 09:06:56] DEBUG[13656] threadpool.c: Increasing threadpool Sorcery's size by 1 [Nov 16 09:06:56] DEBUG[13657] threadpool.c: Increasing threadpool stasis-core's size by 1 [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg SUBSCRIBE/cseq=32160 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002b to use for Request msg SUBSCRIBE/cseq=32160 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-one' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-three' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Identify checks by IP address failed to find match: '10.24.20.43:5060' did not match any identify section rules [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Identified by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1603-auth'. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] SECURITY[13753] res_security_log.c: SecurityEvent="ChallengeSent",EventTV="2017-11-16T09:06:56.949-0600",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="1603",SessionID="UvRtrQ-T7gxkOWtzGgXMiTVFm1sPH68b",LocalAddress="IPV4/UDP/10.27.69.53/5060",RemoteAddress="IPV4/UDP/10.24.20.43/5060",Challenge="" [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg SUBSCRIBE/cseq=32161 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002b to use for Request msg SUBSCRIBE/cseq=32161 (rdata0x7ff69c297688) [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-one' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.24.20.43:5060 does not match identify 'remote-system-three' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Identify checks by IP address failed to find match: '10.24.20.43:5060' did not match any identify section rules [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_endpoint_identifier_user.c: Identified by From username '1603' domain '10.27.69.53' [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1603-auth'. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_authenticator_digest.c: Calculated nonce 1510844816/0c0fb60890e2d2a8fecca25c85cbd692. Actual nonce is 1510844816/0c0fb60890e2d2a8fecca25c85cbd692 [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:06:56] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:06:56] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:06:56] DEBUG[20181] res_pjsip_pubsub.c: Body generator 0x7ff67df20140 found for accept type application/simple-message-summary [Nov 16 09:06:56] DEBUG[20181] res_pjsip_pubsub.c: Subscription '1603->1603' is not to a list [Nov 16 09:06:56] DEBUG[20181] res_pjsip_mwi.c: AOR 1603 has no configured mailboxes. MWI subscription failed. [Nov 16 09:06:56] SECURITY[13753] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2017-11-16T09:06:56.953-0600",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="1603",SessionID="UvRtrQ-T7gxkOWtzGgXMiTVFm1sPH68b",LocalAddress="IPV4/UDP/10.27.69.53/5060",RemoteAddress="IPV4/UDP/10.24.20.43/5060",UsingPassword="1" [Nov 16 09:07:10] DEBUG[13747] res_pjsip_registrar_expire.c: Woke up at 1510844830 Interval: 30 [Nov 16 09:07:10] DEBUG[13747] res_pjsip_registrar_expire.c: Expiring 0 contacts [Nov 16 09:07:16] DEBUG[20198] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:07:16] DEBUG[13657] threadpool.c: Destroying worker thread 4025 [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=13994 (rdata0x7ff69c297688) [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002c to use for Request msg INVITE/cseq=13994 (rdata0x7ff69c297688) [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:36] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Source address 10.27.69.54:5060 matches identify 'remote-system-one' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint remote-system-one [Nov 16 09:07:36] DEBUG[20181] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002c to use for Request msg INVITE/cseq=13994 (rdata0x7ff69c2a0338) [Nov 16 09:07:36] VERBOSE[20181] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '10.27.69.53' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Method is INVITE, Response is 100 Trying [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is INCOMING [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4003028 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is INCOMING [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '0.0.0.0' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '0.0.0.0' and port ''. [Nov 16 09:07:36] DEBUG[20181] res_pjsip_sdp_rtp.c: Transport local-transport-udp bound to 0.0.0.0: Using it for RTP media. [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7ff69c0c3270' [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Allocated port 19988 for RTP instance '0x7ff69c0c3270' [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:19988 (19988) for RTP instance '0x7ff69c0c3270' [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: RTP instance '0x7ff69c0c3270' is setup and ready to go [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting 'ast-directmedia-2' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host 'ast-directmedia-2' and port ''. [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a217400 [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a217400 [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c01ef18) from 0x7ff69a217400 to 0x7ff69c0c3438 [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c01c318) from 0x7ff69a217400 to 0x7ff69c0c3438 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Media stream 'audio' handled by audio [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:36] DEBUG[13657] threadpool.c: Increasing threadpool stasis-core's size by 1 [Nov 16 09:07:36] DEBUG[20181] channel.c: Channel 0x7ff69c276080 'PJSIP/remote-system-one-00000030' allocated [Nov 16 09:07:36] DEBUG[20181] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/remote-system-one-00000030 [Nov 16 09:07:36] DEBUG[20201][C-00000017] pbx_variables.c: Result of 'EXTEN' is '1603' [Nov 16 09:07:36] DEBUG[20201][C-00000017] pbx_variables.c: Result of '1603' is 'PJSIP/1603' [Nov 16 09:07:36] DEBUG[20201][C-00000017] pbx.c: Launching 'Dial' [Nov 16 09:07:36] VERBOSE[20201][C-00000017] pbx.c: Executing [1603@sip:1] Dial("PJSIP/remote-system-one-00000030", "PJSIP/1603") in new stack [Nov 16 09:07:36] DEBUG[20181] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Nov 16 09:07:36] DEBUG[20181] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Nov 16 09:07:36] DEBUG[20181] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Nov 16 09:07:36] DEBUG[20181] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Nov 16 09:07:36] DEBUG[20181] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Nov 16 09:07:36] DEBUG[20201][C-00000017] channel.c: Channel 0x7ff69c232fe0 'PJSIP/1603-00000031' allocated [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '0.0.0.0' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '0.0.0.0' and port ''. [Nov 16 09:07:36] DEBUG[20181] res_pjsip_sdp_rtp.c: Transport local-transport-udp bound to 0.0.0.0: Using it for RTP media. [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Allocated port 14678 for RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:14678 (14678) for RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:36] DEBUG[20181] rtp_engine.c: RTP instance '0x7ff69c0c66e0' is setup and ready to go [Nov 16 09:07:36] DEBUG[20181] netsock2.c: Splitting 'ast-directmedia-2' into... [Nov 16 09:07:36] DEBUG[20181] netsock2.c: ...host 'ast-directmedia-2' and port ''. [Nov 16 09:07:36] DEBUG[20181] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:36] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:36] VERBOSE[20201][C-00000017] app_dial.c: Called PJSIP/1603 [Nov 16 09:07:36] DEBUG[20201][C-00000017] channel.c: PJSIP/remote-system-one-00000030: Dropping redundant connected line update "dPhone D40B" <1603>. [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 100/INVITE/cseq=29040 (rdata0x7ff69c297688) [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Response is 100 Trying [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 180/INVITE/cseq=29040 (rdata0x7ff69c297688) [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Response is 180 ringing [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Response is 180 ringing [Nov 16 09:07:36] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - 1603 [Nov 16 09:07:36] DEBUG[13668] devicestate.c: Changing state for PJSIP/1603 - state 6 (Ringing) [Nov 16 09:07:36] DEBUG[13670] app_queue.c: Extension 'auto_hint_1603@sip' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Nov 16 09:07:36] DEBUG[13754] app_queue.c: Device 'PJSIP/1603' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Nov 16 09:07:36] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_pubsub.c: evsub 0x7ff69c1d67d8 state ACTIVE event USER sub_tree 0x7ff69c16a700 sub_tree state Normal [Nov 16 09:07:36] DEBUG[20181] res_pjsip_pubsub.c: Updating persistence for '1603->auto_hint_1603' [Nov 16 09:07:36] VERBOSE[20201][C-00000017] app_dial.c: PJSIP/1603-00000031 is ringing [Nov 16 09:07:36] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:36] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 2 (In use) [Nov 16 09:07:36] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Method is INVITE, Response is 180 Ringing [Nov 16 09:07:36] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4003028 [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:36] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401cf88 for Response msg 200/NOTIFY/cseq=13242 (rdata0x7ff69c297688) [Nov 16 09:07:36] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003e associated with dialog dlg0x7ff6a401cf88 [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 200/INVITE/cseq=29040 (rdata0x7ff69c297688) [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONNECTING [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:37] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:37] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:07:37] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:07:37] DEBUG[20181] acl.c: For destination '10.24.20.43', our source address is '10.27.69.53'. [Nov 16 09:07:37] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:37] VERBOSE[20181] res_rtp_asterisk.c: 0x7ff69c198140 -- Strict RTP learning after remote address set to: 10.24.20.43:4012 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216cb0 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216cb0 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c0dbb68) from 0x7ff69a216cb0 to 0x7ff69c0c68a8 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c0887f8) from 0x7ff69a216cb0 to 0x7ff69c0c68a8 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:37] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - 1603 [Nov 16 09:07:37] DEBUG[13668] devicestate.c: Changing state for PJSIP/1603 - state 2 (In use) [Nov 16 09:07:37] DEBUG[13670] app_queue.c: Extension 'auto_hint_1603@sip' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 16 09:07:37] DEBUG[13754] app_queue.c: Device 'PJSIP/1603' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 16 09:07:37] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_pubsub.c: evsub 0x7ff69c1d67d8 state ACTIVE event USER sub_tree 0x7ff69c16a700 sub_tree state Normal [Nov 16 09:07:37] DEBUG[20181] res_pjsip_pubsub.c: Updating persistence for '1603->auto_hint_1603' [Nov 16 09:07:37] VERBOSE[20201][C-00000017] app_dial.c: PJSIP/1603-00000031 answered PJSIP/remote-system-one-00000030 [Nov 16 09:07:37] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:37] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 2 (In use) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:37] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:37] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:37] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:37] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:07:37] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:37] VERBOSE[20181] res_rtp_asterisk.c: 0x7ff69c2400c0 -- Strict RTP learning after remote address set to: 10.27.69.54:11380 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216ea0 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216ea0 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c0c43c8) from 0x7ff69a216ea0 to 0x7ff69c0c3438 [Nov 16 09:07:37] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c18f398) from 0x7ff69a216ea0 to 0x7ff69c0c3438 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Method is INVITE, Response is 200 OK [Nov 16 09:07:37] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONNECTING [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is TX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4003028) [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4003028 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONNECTING [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a' can not use native RTP bridge as two channels are required [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology constructor [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology start [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c122ad0(PJSIP/1603-00000031) is joining [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: pushing 0x7ff69c122ad0(PJSIP/1603-00000031) [Nov 16 09:07:37] VERBOSE[20202][C-00000017] bridge_channel.c: Channel PJSIP/1603-00000031 joined 'simple_bridge' basic-bridge [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a' can not use native RTP bridge as two channels are required [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Request msg ACK/cseq=13994 (rdata0x7ff69c297688) [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4003028 [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The transaction state change event is USER [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONNECTING [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event RX_MSG [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:37] DEBUG[20181] res_pjsip_session.c: Method is ACK [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is already using the new technology. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c122ad0(PJSIP/1603-00000031) is joining simple_bridge technology [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) is joining [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: pushing 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) [Nov 16 09:07:37] VERBOSE[20201][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000030 joined 'simple_bridge' basic-bridge [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Checking compatability for channels 'PJSIP/1603-00000031' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:37] VERBOSE[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: switching from simple_bridge technology to native_rtp [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling native_rtp technology constructor [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: moving 0x7ff69c122ad0(PJSIP/1603-00000031) to dummy bridge temporarily [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c122ad0(PJSIP/1603-00000031) is leaving simple_bridge technology (dummy) [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology stop [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) is joining native_rtp technology [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Channel 'PJSIP/remote-system-one-00000030' is joining bridge tech [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Attaching hook data 0x7ff69c120670 to 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c122ad0(PJSIP/1603-00000031) is joining native_rtp technology [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Channel 'PJSIP/1603-00000031' is joining bridge tech [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Attaching hook data 0x7ff69c1345e0 to 'PJSIP/1603-00000031' [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Tech starting 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' with target 'none' [Nov 16 09:07:37] VERBOSE[20201][C-00000017] bridge_native_rtp.c: Locally RTP bridged 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' in stack [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling native_rtp technology start [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology destructor [Nov 16 09:07:37] DEBUG[13657] threadpool.c: Increasing threadpool stasis-core's size by 1 [Nov 16 09:07:37] DEBUG[13669] cdr.c: Finalized CDR for PJSIP/1603-00000031 - start 1510844856.034047 answer 1510844857.476701 end 1510844857.481118 dispo ANSWERED [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Checking compatability for channels 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:37] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is already using the new technology. [Nov 16 09:07:37] DEBUG[13657] threadpool.c: Increasing threadpool stasis-core's size by 1 [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Checking compatability for channels 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:37] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is already using the new technology. [Nov 16 09:07:37] VERBOSE[20202][C-00000017] res_rtp_asterisk.c: 0x7ff69c198140 -- Strict RTP switching to RTP target address 10.24.20.43:4012 as source [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401cf88 for Response msg 200/NOTIFY/cseq=13243 (rdata0x7ff69c297688) [Nov 16 09:07:37] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003e associated with dialog dlg0x7ff6a401cf88 [Nov 16 09:07:37] DEBUG[20202][C-00000017] res_rtp_asterisk.c: Got RTCP report of 40 bytes from 10.24.20.43:4013 [Nov 16 09:07:37] VERBOSE[20201][C-00000017] res_rtp_asterisk.c: 0x7ff69c2400c0 -- Strict RTP switching to RTP target address 10.27.69.54:11380 as source [Nov 16 09:07:38] VERBOSE[20201][C-00000017] res_rtp_asterisk.c: 0x7ff69c2400c0 -- Strict RTP learning complete - Locking on source address 10.27.69.54:11380 [Nov 16 09:07:38] VERBOSE[20202][C-00000017] res_rtp_asterisk.c: 0x7ff69c198140 -- Strict RTP learning complete - Locking on source address 10.24.20.43:4012 [Nov 16 09:07:40] DEBUG[13747] res_pjsip_registrar_expire.c: Woke up at 1510844860 Interval: 30 [Nov 16 09:07:40] DEBUG[13747] res_pjsip_registrar_expire.c: Expiring 0 contacts [Nov 16 09:07:41] DEBUG[20202][C-00000017] res_rtp_asterisk.c: Got RTCP report of 72 bytes from 10.24.20.43:4013 [Nov 16 09:07:42] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Request msg INVITE/cseq=7569 (rdata0x7ff69c297688) [Nov 16 09:07:42] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:42] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:07:42] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:07:42] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 111 based on m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0x7ff69a217120 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c15da18) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 8 (0x7ff69c11e0c8) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 9 (0x7ff69c11b158) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 18 (0x7ff69c108258) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 96 (0x7ff69c03c838) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 111 (0x7ff69c023298) from 0x7ff69a217120 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Media stream 'audio' handled by audio [Nov 16 09:07:42] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:42] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:42] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:42] DEBUG[20181] netsock2.c: Splitting '10.24.20.43' into... [Nov 16 09:07:42] DEBUG[20181] netsock2.c: ...host '10.24.20.43' and port ''. [Nov 16 09:07:42] DEBUG[20181] acl.c: For destination '10.24.20.43', our source address is '10.27.69.53'. [Nov 16 09:07:42] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 111 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0x7ff69a216bc0 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c233bd8) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 8 (0x7ff69c0b7628) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 9 (0x7ff69c274a68) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 18 (0x7ff69c18fa88) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 96 (0x7ff69c205188) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] rtp_engine.c: Copying payload 111 (0x7ff69c232eb8) from 0x7ff69a216bc0 to 0x7ff69c0c68a8 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:42] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4072608) [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4072608 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4072608) [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4072608 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:42] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Checking compatability for channels 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' [Nov 16 09:07:42] DEBUG[20202][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:42] DEBUG[20202][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:42] DEBUG[20202][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:42] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is already using the new technology. [Nov 16 09:07:42] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Tech stopping 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' with target 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:42] DEBUG[20201][C-00000017] bridge_native_rtp.c: Discontinued RTP bridging of 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' - media will flow through Asterisk core [Nov 16 09:07:42] VERBOSE[20201][C-00000017] res_musiconhold.c: Started music on hold, class 'default', on channel 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:42] DEBUG[20201][C-00000017] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Nov 16 09:07:42] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '8' (On Hold) but we don't care because they're not a member of any queue. [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20201][C-00000017] channel.c: Channel PJSIP/remote-system-one-00000030 setting write format path: slin -> ulaw [Nov 16 09:07:42] DEBUG[20201][C-00000017] res_musiconhold.c: PJSIP/remote-system-one-00000030 Opened file 0 '/var/lib/asterisk/moh/macroform-the_simplicity' [Nov 16 09:07:42] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Request msg ACK/cseq=7569 (rdata0x7ff69c297688) [Nov 16 09:07:42] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4072608 [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The transaction state change event is USER [Nov 16 09:07:42] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20201][C-00000017] res_rtp_asterisk.c: Got RTCP report of 64 bytes from 10.27.69.54:11381 [Nov 16 09:07:42] DEBUG[20201][C-00000017] res_rtp_asterisk.c: 0x7ff69c2400c0 -- RTCP from 10.27.69.54:11381: Skipping record, received SSRC '38713448' != expected '190091250' [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:42] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:43] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:44] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[20202][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c0c66e0' so dropping frame [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Request msg REFER/cseq=7570 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAS REFER transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Trying [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Method is REFER [Nov 16 09:07:45] DEBUG[20181] res_pjsip_refer.c: Created progress monitor '0x7ff69a217748' for transfer occurring from channel 'PJSIP/1603-00000031' and endpoint '1603' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_refer.c: Accepting REFER request for progress monitor '0x7ff69c078d30' [Nov 16 09:07:45] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603(PJSIP/1603-00000031)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAS REFER transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] parking/parking_bridge_features.c: Checking if 1607@sip is a parking exten [Nov 16 09:07:45] DEBUG[20181] bridge_channel.c: Setting 0x7ff69c122ad0(PJSIP/1603-00000031) state from:0 to:2 [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: pulling 0x7ff69c122ad0(PJSIP/1603-00000031) [Nov 16 09:07:45] VERBOSE[20202][C-00000017] bridge_channel.c: Channel PJSIP/1603-00000031 left 'native_rtp' basic-bridge [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c122ad0(PJSIP/1603-00000031) is leaving native_rtp technology [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Channel 'PJSIP/1603-00000031' is leaving bridge tech [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Detaching hook data 0x7ff69c0dd4b8 from 'PJSIP/1603-00000031' [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Tech stopping 'PJSIP/remote-system-one-00000030' and 'PJSIP/1603-00000031' with target 'none' [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7ff69c1345e0 [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_channel.c: Channel PJSIP/1603-00000031 will survive this bridge; clearing outgoing (dialed) flag [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a' can not use native RTP bridge as two channels are required [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:45] VERBOSE[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: switching from native_rtp technology to simple_bridge [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology constructor [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: moving 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) to dummy bridge temporarily [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) is leaving native_rtp technology (dummy) [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Channel 'PJSIP/remote-system-one-00000030' is leaving bridge tech [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a'. Detaching hook data 0x7ff69c01c318 from 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7ff69c120670 [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling native_rtp technology stop [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) is joining simple_bridge technology [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology start [Nov 16 09:07:45] DEBUG[20202][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling native_rtp technology destructor [Nov 16 09:07:45] DEBUG[20202][C-00000017] channel.c: Channel 0x7ff69c232fe0 'PJSIP/1603-00000031' hanging up. Refs: 2 [Nov 16 09:07:45] DEBUG[20202][C-00000017] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Nov 16 09:07:45] DEBUG[13669] cdr.c: Finalized CDR for PJSIP/remote-system-one-00000030 - start 1510844856.032394 answer 1510844857.478336 end 1510844865.767678 dispo ANSWERED [Nov 16 09:07:45] DEBUG[20181] channel.c: Channel 0x7ff69c232fe0 'PJSIP/1603-00000031' destroying [Nov 16 09:07:45] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - 1603 [Nov 16 09:07:45] DEBUG[13668] devicestate.c: Changing state for PJSIP/1603 - state 1 (Not in use) [Nov 16 09:07:45] DEBUG[13670] app_queue.c: Extension 'auto_hint_1603@sip' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 16 09:07:45] DEBUG[13754] app_queue.c: Device 'PJSIP/1603' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 16 09:07:45] DEBUG[13669] cdr.c: CDR for PJSIP/1603-00000031 is dialed and has no Party B; discarding [Nov 16 09:07:45] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_pubsub.c: evsub 0x7ff69c1d67d8 state ACTIVE event USER sub_tree 0x7ff69c16a700 sub_tree state Normal [Nov 16 09:07:45] DEBUG[20181] res_pjsip_pubsub.c: Updating persistence for '1603->auto_hint_1603' [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge 'c99bad84-7c95-4651-80bb-eda6c16e854a' can not use native RTP bridge as two channels are required [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is already using the new technology. [Nov 16 09:07:45] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:45] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 2 (In use) [Nov 16 09:07:45] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 16 09:07:45] VERBOSE[20201][C-00000017] res_musiconhold.c: Stopped music on hold on PJSIP/remote-system-one-00000030 [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Channel PJSIP/remote-system-one-00000030 setting write format path: ulaw -> ulaw [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Soft-Hanging (0x02) up channel 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge_channel.c: Setting 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) state from:0 to:1 [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: pulling 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) [Nov 16 09:07:45] VERBOSE[20201][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000030 left 'simple_bridge' basic-bridge [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge_channel.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: 0x7ff69c21f7f0(PJSIP/remote-system-one-00000030) is leaving simple_bridge technology [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: dissolving bridge with cause 16(Normal Clearing) [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: queueing action type:13 sub:1001 [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a is dissolved, not performing smart bridge operation. [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: actually destroying basic bridge, nobody wants it anymore [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling basic bridge destructor [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology stop [Nov 16 09:07:45] DEBUG[20201][C-00000017] bridge.c: Bridge c99bad84-7c95-4651-80bb-eda6c16e854a: calling simple_bridge technology destructor [Nov 16 09:07:45] DEBUG[20201][C-00000017] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Nov 16 09:07:45] DEBUG[20201][C-00000017] pbx_variables.c: Result of 'EXTEN' is '1607' [Nov 16 09:07:45] DEBUG[20201][C-00000017] pbx_variables.c: Result of '1607' is 'PJSIP/1607' [Nov 16 09:07:45] DEBUG[20201][C-00000017] pbx.c: Launching 'Dial' [Nov 16 09:07:45] VERBOSE[20201][C-00000017] pbx.c: Executing [1607@sip:1] Dial("PJSIP/remote-system-one-00000030", "PJSIP/1607@remote-system-one") in new stack [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401cf88 for Response msg 200/NOTIFY/cseq=13244 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003e associated with dialog dlg0x7ff6a401cf88 [Nov 16 09:07:45] DEBUG[13679] threadpool.c: Increasing threadpool SIP's size by 5 [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Channel 0x7ff69c232fe0 'PJSIP/remote-system-one-00000032' allocated [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Inheriting variable __SIPREFERREDBYHDR from PJSIP/remote-system-one-00000030 to PJSIP/remote-system-one-00000032. [Nov 16 09:07:45] DEBUG[20181] netsock2.c: Splitting '0.0.0.0' into... [Nov 16 09:07:45] DEBUG[20181] netsock2.c: ...host '0.0.0.0' and port ''. [Nov 16 09:07:45] DEBUG[20181] res_pjsip_sdp_rtp.c: Transport local-transport-udp bound to 0.0.0.0: Using it for RTP media. [Nov 16 09:07:45] DEBUG[20181] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7ff69c146a50' [Nov 16 09:07:45] DEBUG[20181] res_rtp_asterisk.c: Allocated port 14704 for RTP instance '0x7ff69c146a50' [Nov 16 09:07:45] DEBUG[20181] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:14704 (14704) for RTP instance '0x7ff69c146a50' [Nov 16 09:07:45] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:45] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:45] DEBUG[20181] netsock2.c: Splitting '10.27.69.53' into... [Nov 16 09:07:45] DEBUG[20181] netsock2.c: ...host '10.27.69.53' and port ''. [Nov 16 09:07:45] DEBUG[20181] rtp_engine.c: RTP instance '0x7ff69c146a50' is setup and ready to go [Nov 16 09:07:45] DEBUG[20181] netsock2.c: Splitting 'ast-directmedia-2' into... [Nov 16 09:07:45] DEBUG[20181] netsock2.c: ...host 'ast-directmedia-2' and port ''. [Nov 16 09:07:45] DEBUG[20181] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c146a50' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:45] VERBOSE[20201][C-00000017] app_dial.c: Called PJSIP/1607@remote-system-one [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:45] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Response msg 100/INVITE/cseq=24294 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CALLING [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Response is 100 Trying [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Response msg 180/INVITE/cseq=24294 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Response is 180 Ringing [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Proceeding [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is EARLY [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Response is 180 Ringing [Nov 16 09:07:45] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:45] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 7 (Ring+Inuse) [Nov 16 09:07:45] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '7' (Ring+Inuse) but we don't care because they're not a member of any queue. [Nov 16 09:07:45] VERBOSE[20201][C-00000017] app_dial.c: PJSIP/remote-system-one-00000032 is ringing [Nov 16 09:07:45] DEBUG[20181] res_pjsip_refer.c: Sending initial 100 Trying NOTIFY for progress monitor '0x7ff69c078d30' [Nov 16 09:07:45] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a40d5188 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] res_pjsip_refer.c: Sending NOTIFY with response '180' and state '4' on subscription '0x7ff69c1885d8' and progress monitor '0x7ff69c078d30' [Nov 16 09:07:45] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff69c0d20b8 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:45] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 7 (Ring+Inuse) [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Driver for channel 'PJSIP/remote-system-one-00000030' does not support indication 3, emulating it [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Channel PJSIP/remote-system-one-00000030 setting write format path: slin -> ulaw [Nov 16 09:07:45] DEBUG[20201][C-00000017] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 200/NOTIFY/cseq=29041 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a40d5188 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: NOTIFY received final response code 200 [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 200/NOTIFY/cseq=29042 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff69c0d20b8 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: NOTIFY received final response code 200 [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: Difference is 704, ms is 108 [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Request msg BYE/cseq=7571 (rdata0x7ff69c297688) [Nov 16 09:07:45] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/1603-00000081 associated with dialog dlg0x7ff6a40eb658 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Method is BYE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:45] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:45] DEBUG[20181] rtp_engine.c: Destroyed RTP instance '0x7ff69c0c66e0' [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:45] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:46] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: Got RTCP report of 64 bytes from 10.27.69.54:11381 [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: 0x7ff69c2400c0 -- RTCP from 10.27.69.54:11381: Skipping record, received SSRC '38713448' != expected '190091250' [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:47] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[20201][C-00000017] res_rtp_asterisk.c: No remote address on RTP instance '0x7ff69c146a50' so dropping frame [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Response msg 200/INVITE/cseq=24294 (rdata0x7ff69c297688) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONNECTING [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:48] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:48] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:48] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:48] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:07:48] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c146a50' [Nov 16 09:07:48] VERBOSE[20181] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Strict RTP learning after remote address set to: 10.27.69.54:18656 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216cb0 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216cb0 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c13aae8) from 0x7ff69a216cb0 to 0x7ff69c146c18 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c231ab8) from 0x7ff69a216cb0 to 0x7ff69c146c18 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:48] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:07:48] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 2 (In use) [Nov 16 09:07:48] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 16 09:07:48] VERBOSE[20201][C-00000017] app_dial.c: PJSIP/remote-system-one-00000032 answered PJSIP/remote-system-one-00000030 [Nov 16 09:07:48] DEBUG[20201][C-00000017] channel.c: Channel PJSIP/remote-system-one-00000030 setting write format path: ulaw -> ulaw [Nov 16 09:07:48] DEBUG[20201][C-00000017] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34' can not use native RTP bridge as two channels are required [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling simple_bridge technology constructor [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling simple_bridge technology start [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) is joining [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: pushing 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) [Nov 16 09:07:48] VERBOSE[20210][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000032 joined 'simple_bridge' basic-bridge <22c91158-1cd2-460a-8447-d687f5d2bc34> [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34' can not use native RTP bridge as two channels are required [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) is joining simple_bridge technology [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c120a60(PJSIP/remote-system-one-00000030) is joining [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: pushing 0x7ff69c120a60(PJSIP/remote-system-one-00000030) [Nov 16 09:07:48] VERBOSE[20201][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000030 joined 'simple_bridge' basic-bridge <22c91158-1cd2-460a-8447-d687f5d2bc34> [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34' can not use native RTP bridge as channel 'PJSIP/remote-system-one-00000030' has features which prevent it [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology simple_bridge [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c120a60(PJSIP/remote-system-one-00000030) is joining simple_bridge technology [Nov 16 09:07:48] DEBUG[13669] cdr.c: Finalized CDR for PJSIP/remote-system-one-00000032 - start 1510844865.782043 answer 1510844868.935241 end 1510844868.937143 dispo ANSWERED [Nov 16 09:07:48] DEBUG[20200] res_pjsip_refer.c: Detaching REFER progress monitoring hook from 'PJSIP/remote-system-one-00000030' as it has joined a bridge [Nov 16 09:07:48] DEBUG[20181] res_pjsip_refer.c: Subscription '0x7ff69c1885d8' is being terminated as a result of a NOTIFY, removing REFER progress structure early on progress monitor '0x7ff69c078d30' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_refer.c: Sending NOTIFY with response '200' and state '5' on subscription '0x7ff69c1885d8' and progress monitor '0x7ff69c078d30' [Nov 16 09:07:48] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint '1603()' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a401b328 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Destroying SIP session with endpoint 1603 [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:48] VERBOSE[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: switching from simple_bridge technology to native_rtp [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling native_rtp technology constructor [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: moving 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) to dummy bridge temporarily [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: moving 0x7ff69c120a60(PJSIP/remote-system-one-00000030) to dummy bridge temporarily [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) is leaving simple_bridge technology (dummy) [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c120a60(PJSIP/remote-system-one-00000030) is leaving simple_bridge technology (dummy) [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling simple_bridge technology stop [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) is joining native_rtp technology [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Channel 'PJSIP/remote-system-one-00000032' is joining bridge tech [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Attaching hook data 0x7ff69c066f10 to 'PJSIP/remote-system-one-00000032' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c120a60(PJSIP/remote-system-one-00000030) is joining native_rtp technology [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Channel 'PJSIP/remote-system-one-00000030' is joining bridge tech [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Attaching hook data 0x7ff69c1ee4e0 to 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Tech starting 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' with target 'none' [Nov 16 09:07:48] VERBOSE[20201][C-00000017] bridge_native_rtp.c: Remotely bridged 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' - media will flow directly between them [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling native_rtp technology start [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling simple_bridge technology destructor [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg INVITE/cseq=23358 (rdata0x7ff69c2bf4b8) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:48] DEBUG[20205] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000032; initiating direct media update [Nov 16 09:07:48] DEBUG[20205] acl.c: Attached to given IP address [Nov 16 09:07:48] DEBUG[20205] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c146a50' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:48] DEBUG[20205] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a407ce58) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Received request [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Response msg 491/INVITE/cseq=24295 (rdata0x7ff69c2bd3e8) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Response is 491 Another INVITE transaction in progress [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' re-INVITE collision. [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg ACK/cseq=23358 (rdata0x7ff69c2bd3e8) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Confirmed [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Method is ACK [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:48] DEBUG[20181] res_pjsip_refer.c: Not sending NOTIFY of response '503' and state '5' on progress monitor '0x7ff69c078d30' as subscription has been terminated [Nov 16 09:07:48] DEBUG[20205] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000030; initiating direct media update [Nov 16 09:07:48] DEBUG[20205] acl.c: Attached to given IP address [Nov 16 09:07:48] DEBUG[20205] res_rtp_asterisk.c: Failed to setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:48] DEBUG[20205] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a403e388) [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a403e388 [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:48] DEBUG[20205] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a40eb658 for Response msg 200/NOTIFY/cseq=29043 (rdata0x7ff69c2f8218) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/NOTIFY/cseq=29043 (rdata0x7ff69c2f8218). Using request transaction as basis. [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7ff6a401b328 for Response msg 200/NOTIFY/cseq=29043 (rdata0x7ff69c2f8218). [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000033 to use for Response msg 200/NOTIFY/cseq=29043 (rdata0x7ff69c2f8218) [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a401b328 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Response msg 200/INVITE/cseq=14224 (rdata0x7ff69c2f8218) [Nov 16 09:07:48] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:48] DEBUG[20181] acl.c: Attached to given IP address [Nov 16 09:07:48] DEBUG[20181] res_rtp_asterisk.c: Failed to setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:48] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:48] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:48] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216c50 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216c50 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c1d8b38) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:07:48] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c211cf8) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a403e388 [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:48] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:48] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:48] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:48] DEBUG[20201][C-00000017] channel.c: Dropping duplicate answer! [Nov 16 09:07:48] VERBOSE[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Strict RTP switching to RTP target address 10.27.69.54:18656 as source [Nov 16 09:07:49] DEBUG[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Received RTP packet from 10.24.18.197:4010, dropping due to strict RTP protection. Qualifying new stream. [Nov 16 09:07:49] DEBUG[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Received RTP packet from 10.24.18.197:4010, dropping due to strict RTP protection. Will switch to it in 3 packets. [Nov 16 09:07:49] DEBUG[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Received RTP packet from 10.24.18.197:4010, dropping due to strict RTP protection. Will switch to it in 2 packets. [Nov 16 09:07:49] DEBUG[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Received RTP packet from 10.24.18.197:4010, dropping due to strict RTP protection. Will switch to it in 1 packets. [Nov 16 09:07:49] VERBOSE[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Strict RTP switching source address to 10.24.18.197:4010 [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg INVITE/cseq=23359 (rdata0x7ff69c2f8218) [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:49] DEBUG[20181] netsock2.c: Splitting '10.24.18.197' into... [Nov 16 09:07:49] DEBUG[20181] netsock2.c: ...host '10.24.18.197' and port ''. [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a217120 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a217120 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c143018) from 0x7ff69a217120 to 0x7ff69c146c18 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c1e8968) from 0x7ff69a217120 to 0x7ff69c146c18 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Media stream 'audio' handled by audio [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:49] DEBUG[20181] netsock2.c: Splitting '10.24.18.197' into... [Nov 16 09:07:49] DEBUG[20181] netsock2.c: ...host '10.24.18.197' and port ''. [Nov 16 09:07:49] DEBUG[20181] acl.c: For destination '10.24.18.197', our source address is '10.27.69.53'. [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c146a50' [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216bc0 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c285e48) from 0x7ff69a216bc0 to 0x7ff69c146c18 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c205188) from 0x7ff69a216bc0 to 0x7ff69c146c18 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:49] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a403e388) [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a403e388 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a403e388) [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a403e388 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg ACK/cseq=23359 (rdata0x7ff69c2f8218) [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a403e388 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The transaction state change event is USER [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' INVITE delay check. tsx-state:Terminated [Nov 16 09:07:49] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:49] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:49] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:49] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:49] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Tech starting 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' with target 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Sending 'PJSIP/remote-system-one-00000030' back to remote [Nov 16 09:07:49] DEBUG[20181] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000030; initiating direct media update [Nov 16 09:07:49] DEBUG[20181] acl.c: Attached to given IP address [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:49] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4062e08) [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a4062e08 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Response msg 200/INVITE/cseq=14225 (rdata0x7ff69c2f8218) [Nov 16 09:07:49] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:49] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:07:49] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:07:49] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:07:49] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216c50 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216c50 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c1de078) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:07:49] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c127118) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a4062e08 [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:49] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:49] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:49] DEBUG[20201][C-00000017] channel.c: Dropping duplicate answer! [Nov 16 09:07:49] DEBUG[20201][C-00000017] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x7ff69c0c3270' [Nov 16 09:07:50] VERBOSE[20210][C-00000017] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Strict RTP learning complete - Locking on source address 10.24.18.197:4010 [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a40d5188 [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff69c0d20b8 [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:07:50] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:52] DEBUG[13681] res_pjsip_session.c: Endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' re-INVITE collision timer expired. [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' sending delayed INVITE request. [Nov 16 09:07:52] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:52] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:07:52] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff69c0d20b8) [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff69c0d20b8 [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:52] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Response msg 200/INVITE/cseq=24296 (rdata0x7ff69c2f8218) [Nov 16 09:07:52] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:52] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:07:52] DEBUG[20181] netsock2.c: Splitting '10.24.18.197' into... [Nov 16 09:07:52] DEBUG[20181] netsock2.c: ...host '10.24.18.197' and port ''. [Nov 16 09:07:52] DEBUG[20181] acl.c: For destination '10.24.18.197', our source address is '10.27.69.53'. [Nov 16 09:07:52] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c146a50' [Nov 16 09:07:52] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216c50 [Nov 16 09:07:52] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216c50 [Nov 16 09:07:52] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c25b248) from 0x7ff69a216c50 to 0x7ff69c146c18 [Nov 16 09:07:52] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c232eb8) from 0x7ff69a216c50 to 0x7ff69c146c18 [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff69c0d20b8 [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:07:52] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:07:52] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:07:52] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:07:52] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:07:52] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:07:52] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:07:52] DEBUG[20210][C-00000017] channel.c: Dropping duplicate answer! [Nov 16 09:07:52] DEBUG[20210][C-00000017] res_rtp_asterisk.c: Got RTCP report of 72 bytes from 10.24.18.197:4011 [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The UAC NOTIFY transaction involved in this state change is 0x7ff6a401b328 [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:07:53] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:07:56] DEBUG[20196] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:07:56] DEBUG[20197] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:07:56] DEBUG[13656] threadpool.c: Destroying worker thread 4023 [Nov 16 09:07:56] DEBUG[13656] threadpool.c: Destroying worker thread 4024 [Nov 16 09:08:05] DEBUG[20204] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:05] DEBUG[13657] threadpool.c: Destroying worker thread 4028 [Nov 16 09:08:08] DEBUG[20203] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:08] DEBUG[13657] threadpool.c: Destroying worker thread 4027 [Nov 16 09:08:10] DEBUG[13747] res_pjsip_registrar_expire.c: Woke up at 1510844890 Interval: 30 [Nov 16 09:08:10] DEBUG[13747] res_pjsip_registrar_expire.c: Expiring 0 contacts [Nov 16 09:08:12] DEBUG[20200] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:12] DEBUG[13657] threadpool.c: Destroying worker thread 4026 [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The UAS REFER transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c1cf018 has no ast session [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:08:17] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg INVITE/cseq=23360 (rdata0x7ff69c2ddc08) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Nov 16 09:08:20] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:08:20] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a217120 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a217120 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c1b53a8) from 0x7ff69a217120 to 0x7ff69c146c18 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c108258) from 0x7ff69a217120 to 0x7ff69c146c18 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Media stream 'audio' handled by audio [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:08:20] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:08:20] DEBUG[13657] threadpool.c: Increasing threadpool stasis-core's size by 1 [Nov 16 09:08:20] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] VERBOSE[20181] res_rtp_asterisk.c: 0x7ff69c2ba920 -- Strict RTP learning after remote address set to: 10.27.69.54:18656 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216bc0 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216bc0 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c14f228) from 0x7ff69a216bc0 to 0x7ff69c146c18 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c18e948) from 0x7ff69a216bc0 to 0x7ff69c146c18 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:08:20] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4063638) [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a4063638) [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Tech starting 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' with target 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Sending 'PJSIP/remote-system-one-00000030' back to remote [Nov 16 09:08:20] DEBUG[20181] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000030; initiating direct media update [Nov 16 09:08:20] DEBUG[20181] acl.c: Attached to given IP address [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Failed to setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Method is INVITE [Nov 16 09:08:20] DEBUG[20181] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a40eb658) [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a40eb658 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Response msg 200/INVITE/cseq=14226 (rdata0x7ff69c2bd3e8) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:08:20] DEBUG[20181] acl.c: Attached to given IP address [Nov 16 09:08:20] DEBUG[20181] res_rtp_asterisk.c: Failed to setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:08:20] DEBUG[20181] netsock2.c: Splitting '10.27.69.54' into... [Nov 16 09:08:20] DEBUG[20181] netsock2.c: ...host '10.27.69.54' and port ''. [Nov 16 09:08:20] DEBUG[20181] acl.c: For destination '10.27.69.54', our source address is '10.27.69.53'. [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7ff69a216c50 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7ff69a216c50 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 0 (0x7ff69c205188) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Copying payload 101 (0x7ff69c285e48) from 0x7ff69a216c50 to 0x7ff69c0c3438 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a40eb658 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Checking compatability for channels 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Chose bridge technology native_rtp [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is already using the new technology. [Nov 16 09:08:20] DEBUG[20201][C-00000017] channel.c: Dropping duplicate answer! [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg ACK/cseq=23360 (rdata0x7ff69c2ddc08) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7ff6a4063638 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is USER [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a4003028 for Request msg BYE/cseq=23361 (rdata0x7ff69c2ef568) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/remote-system-one-00000084 associated with dialog dlg0x7ff6a4003028 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received request [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Method is BYE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000032)' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_channel.c: Setting 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) state from:0 to:1 [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: pulling 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) [Nov 16 09:08:20] VERBOSE[20210][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000032 left 'native_rtp' basic-bridge <22c91158-1cd2-460a-8447-d687f5d2bc34> [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c12f5b0(PJSIP/remote-system-one-00000032) is leaving native_rtp technology [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Channel 'PJSIP/remote-system-one-00000032' is leaving bridge tech [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Detaching hook data 0x7ff69c11bf38 from 'PJSIP/remote-system-one-00000032' [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Tech stopping 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' with target 'none' [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Bringing back 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' to us [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Discontinued RTP bridging of 'PJSIP/remote-system-one-00000032' and 'PJSIP/remote-system-one-00000030' - media will flow through Asterisk core [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7ff69c066f10 [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: dissolving bridge with cause 16(Normal Clearing) [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge_channel.c: Setting 0x7ff69c120a60(PJSIP/remote-system-one-00000030) state from:0 to:2 [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: queueing action type:13 sub:1001 [Nov 16 09:08:20] DEBUG[20210][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is dissolved, not performing smart bridge operation. [Nov 16 09:08:20] DEBUG[20210][C-00000017] channel.c: Channel 0x7ff69c232fe0 'PJSIP/remote-system-one-00000032' hanging up. Refs: 3 [Nov 16 09:08:20] DEBUG[20210][C-00000017] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Nov 16 09:08:20] DEBUG[20205] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] DEBUG[20205] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000032; initiating direct media update [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: Not sending reinvite to remote-system-one because of disconnected state... [Nov 16 09:08:20] DEBUG[20205] rtp_engine.c: Destroyed RTP instance '0x7ff69c146a50' [Nov 16 09:08:20] DEBUG[20205] channel.c: Channel 0x7ff69c232fe0 'PJSIP/remote-system-one-00000032' destroying [Nov 16 09:08:20] DEBUG[20205] acl.c: Attached to given IP address [Nov 16 09:08:20] DEBUG[20205] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7ff69c0c3270' [Nov 16 09:08:20] DEBUG[20205] chan_pjsip.c: RTP changed on PJSIP/remote-system-one-00000030; initiating direct media update [Nov 16 09:08:20] DEBUG[20205] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7ff69c0c3270' [Nov 16 09:08:20] DEBUG[20205] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to remote-system-one [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: Method is INVITE [Nov 16 09:08:20] DEBUG[20205] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.27.69.53:5060 (this may be re-written again later) [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one(PJSIP/remote-system-one-00000030)' [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The inv session still has an invite_tsx (0x7ff6a401b328) [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a401b328 [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:08:20] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 2 (In use) [Nov 16 09:08:20] DEBUG[13669] cdr.c: Finalized CDR for PJSIP/remote-system-one-00000030 - start 1510844865.783203 answer 1510844865.783203 end 1510844900.250081 dispo ANSWERED [Nov 16 09:08:20] DEBUG[13669] cdr.c: CDR for PJSIP/remote-system-one-00000032 is dialed and has no Party B; discarding [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: pulling 0x7ff69c120a60(PJSIP/remote-system-one-00000030) [Nov 16 09:08:20] VERBOSE[20201][C-00000017] bridge_channel.c: Channel PJSIP/remote-system-one-00000030 left 'native_rtp' basic-bridge <22c91158-1cd2-460a-8447-d687f5d2bc34> [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_channel.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: 0x7ff69c120a60(PJSIP/remote-system-one-00000030) is leaving native_rtp technology [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Channel 'PJSIP/remote-system-one-00000030' is leaving bridge tech [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Bridge '22c91158-1cd2-460a-8447-d687f5d2bc34'. Detaching hook data 0x7ff69c0b1f48 from 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7ff69c1ee4e0 [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34 is dissolved, not performing smart bridge operation. [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: actually destroying basic bridge, nobody wants it anymore [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling basic bridge destructor [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling native_rtp technology stop [Nov 16 09:08:20] DEBUG[20201][C-00000017] bridge.c: Bridge 22c91158-1cd2-460a-8447-d687f5d2bc34: calling native_rtp technology destructor [Nov 16 09:08:20] DEBUG[20201][C-00000017] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Nov 16 09:08:20] DEBUG[20201][C-00000017] pbx.c: Spawn extension (sip,1607,1) exited non-zero on 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] VERBOSE[20201][C-00000017] pbx.c: Spawn extension (sip, 1607, 1) exited non-zero on 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20201][C-00000017] channel.c: Soft-Hanging (0x10) up channel 'PJSIP/remote-system-one-00000030' [Nov 16 09:08:20] DEBUG[20201][C-00000017] channel.c: Channel 0x7ff69c276080 'PJSIP/remote-system-one-00000030' hanging up. Refs: 2 [Nov 16 09:08:20] DEBUG[20201][C-00000017] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Nov 16 09:08:20] DEBUG[20205] res_pjsip_session.c: Delay sending BYE to remote-system-one because of outstanding transaction... [Nov 16 09:08:20] DEBUG[20205] channel.c: Channel 0x7ff69c276080 'PJSIP/remote-system-one-00000030' destroying [Nov 16 09:08:20] DEBUG[13668] devicestate.c: No provider found, checking channel drivers for PJSIP - remote-system-one [Nov 16 09:08:20] DEBUG[13668] devicestate.c: Changing state for PJSIP/remote-system-one - state 1 (Not in use) [Nov 16 09:08:20] DEBUG[13754] app_queue.c: Device 'PJSIP/remote-system-one' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Response msg 200/INVITE/cseq=14227 (rdata0x7ff69c2c6098) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a401b328 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Endpoint 'remote-system-one()' INVITE delay check. tsx-state:Terminated [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Endpoint 'remote-system-one()' sending delayed BYE request. [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Method is BYE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Calling [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is TX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is CONFIRMED [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7ff6a401ab18 for Response msg 200/BYE/cseq=14228 (rdata0x7ff69c2f8218) [Nov 16 09:08:20] DEBUG[13681] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002c associated with dialog dlg0x7ff6a401ab18 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: There is no transaction involved in this state change [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Source of transaction state change is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current transaction state is Completed [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The transaction state change event is RX_MSG [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Received response [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Response is 200 OK [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: BYE received final response code 200 [Nov 16 09:08:20] DEBUG[20181] rtp_engine.c: Destroyed RTP instance '0x7ff69c0c3270' [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7ff6a407ce58 [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:08:20] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:20] DEBUG[20181] res_pjsip_session.c: Destroying SIP session with endpoint remote-system-one [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The state change pertains to the endpoint 'remote-system-one()' [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7ff6a4093328 [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:08:25] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD [Nov 16 09:08:25] DEBUG[20181] res_pjsip_session.c: Destroying SIP session with endpoint remote-system-one [Nov 16 09:08:40] DEBUG[20211] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:40] DEBUG[13657] threadpool.c: Destroying worker thread 4034 [Nov 16 09:08:40] DEBUG[13747] res_pjsip_registrar_expire.c: Woke up at 1510844920 Interval: 30 [Nov 16 09:08:40] DEBUG[13747] res_pjsip_registrar_expire.c: Expiring 0 contacts [Nov 16 09:08:45] DEBUG[20209] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:45] DEBUG[20208] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:45] DEBUG[20207] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:45] DEBUG[20206] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 16 09:08:45] DEBUG[13679] threadpool.c: Destroying worker thread 4033 [Nov 16 09:08:45] DEBUG[13679] threadpool.c: Destroying worker thread 4032 [Nov 16 09:08:45] DEBUG[13679] threadpool.c: Destroying worker thread 4031 [Nov 16 09:08:45] DEBUG[13679] threadpool.c: Destroying worker thread 4030 [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: inv_session 0x7ff69c01a868 has no ast session [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7ff6b0008e78 [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: The current transaction state is Terminated [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: The transaction state change event is TIMER [Nov 16 09:08:52] DEBUG[13681] res_pjsip_session.c: The current inv state is DISCONNCTD