[Sep 17 15:49:29] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:49:50] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:49:50] VERBOSE[17840] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:50:02] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:02] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:02] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:02] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:02] DEBUG[3665] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=498748188 (rdata0x7f911400a498) [Sep 17 15:50:02] DEBUG[3665] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000023 to use for Request msg INVITE/cseq=498748188 (rdata0x7f911400a498) [Sep 17 15:50:02] DEBUG[3662] threadpool.c: Increasing threadpool pjsip/pool's size by 5 [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Source address 10.200.208.128:5060 does not match identify 'lcr-02' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Source address 10.200.208.128:5060 does not match identify 'zabbix' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Source address 10.200.208.128:5060 does not match identify 'coltiepub' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Source address 10.200.208.128:5060 does not match identify 'lcr-01' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Source address 10.200.208.128:5060 matches identify 'lcr-03' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_endpoint_identifier_ip.c: Identify 'lcr-03' SIP message matched to endpoint lcr-03 [Sep 17 15:50:02] DEBUG[17845] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000023 to use for Request msg INVITE/cseq=498748188 (rdata0x7f911401f888) [Sep 17 15:50:02] VERBOSE[17845] pbx_variables.c: Setting global variable 'SIPDOMAIN' to 'uk-carrier-01-thn.internal.voip.co.uk' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Method is INVITE, Response is 100 Trying [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:02] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current inv state is INCOMING [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current inv state is INCOMING [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.163' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.163' and port ''. [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f9148014090' [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Allocated port 16146 for RTP instance '0x7f9148014090' [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:16146 (16146) for RTP instance '0x7f9148014090' [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.30' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.30' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port ''. [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: RTP instance '0x7f9148014090' is setup and ready to go [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting 'uk-carrier-01-thn' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host 'uk-carrier-01-thn' and port ''. [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f9148014090' [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f912eeb1410 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f912eeb1410 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x7f9148013fb8) from 0x7f912eeb1410 to 0x7f912eeb1410 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Crossover copying tx to rx payload mapping 8 (0x7f9148014008) from 0x7f912eeb1410 to 0x7f912eeb1410 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x7f9148019328) from 0x7f912eeb1410 to 0x7f912eeb1410 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying rx payload mapping 0 (0x7f9148013fb8) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying rx payload mapping 8 (0x7f9148014008) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying rx payload mapping 101 (0x7f9148019328) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 0 (0x7f9148013fb8) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 8 (0x7f9148014008) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 101 (0x7f9148019328) from 0x7f912eeb1410 to 0x7f9148014268 [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Media stream 'audio' handled by audio [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148014090' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:02] DEBUG[17845] stasis.c: Creating topic. name: channel:1568731802.111, detail: [Sep 17 15:50:02] DEBUG[17845] stasis.c: Topic 'channel:1568731802.111': 0x7f914801aba0 created [Sep 17 15:50:02] DEBUG[17845] stasis.c: Creating topic. name: cache:159/channel:1568731802.111, detail: [Sep 17 15:50:02] DEBUG[17845] stasis.c: Topic 'cache:159/channel:1568731802.111': 0x7f9148022720 created [Sep 17 15:50:02] DEBUG[17845] channel.c: Channel 0x7f914801ff80 'PJSIP/lcr-03-0000004a' allocated [Sep 17 15:50:02] DEBUG[17845] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/lcr-03-0000004a [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'GotoIf' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:1] GotoIf("PJSIP/lcr-03-0000004a", "0?voipin-ie-short,+441865251758,1") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_builtins.c: Not taking any branch [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'GotoIf' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:2] GotoIf("PJSIP/lcr-03-0000004a", "0?loopback-audio,+441865251758,1") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_builtins.c: Not taking any branch [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'NoOp' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:3] NoOp("PJSIP/lcr-03-0000004a", ""Dialling +441865251758 via Colt"") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function PJSIP_HEADER(read,Remote-Party-ID) result is ';screen=yes' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Set' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:4] Set("PJSIP/lcr-03-0000004a", "rpidheader=;screen=yes") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Evaluating 'rpidheader' (from 'rpidheader}' len 10) [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'rpidheader' is ';screen=yes' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CUT(rpidheader,@,-1) result is ';screen=yes' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Set' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:16] Set("PJSIP/lcr-03-0000004a", "rpidheader=;screen=yes") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Set' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:17] Set("PJSIP/lcr-03-0000004a", "CALLERID(num)=+441869222503") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'ExecIf' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:18] ExecIf("PJSIP/lcr-03-0000004a", "0?Set(CALLERID(num)=anonymous)") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'GotoIf' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:19] GotoIf("PJSIP/lcr-03-0000004a", "0?withoutid") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_builtins.c: Not taking any branch [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'rpidheader' is ';screen=yes' [Sep 17 15:50:02] DEBUG[17850][C-00000026] func_strings.c: FUNCTION REGEX (privacy=full)(;screen=yes) [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function REGEX("privacy=full" ;screen=yes) result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Set' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:20] Set("PJSIP/lcr-03-0000004a", "PRIVACY=0") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'PRIVACY' is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Expression result is '0' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'GotoIf' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:21] GotoIf("PJSIP/lcr-03-0000004a", "0?withoutid:withid") in new stack [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx_builtins.c: Goto (voipin,+441865251758,22) [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'NoOp' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:22] NoOp("PJSIP/lcr-03-0000004a", ""Send Caller ID"") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Function CALLERID(num) result is '+441869222503' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Set' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:23] Set("PJSIP/lcr-03-0000004a", "PJSIP_HEADER(add,P-Asserted-Identity)="+441869222503" ") in new stack [Sep 17 15:50:02] DEBUG[17845] res_pjsip_header_funcs.c: Adding header P-Asserted-Identity with value "+441869222503" [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx_variables.c: Result of 'EXTEN' is '+441865251758' [Sep 17 15:50:02] DEBUG[17850][C-00000026] pbx.c: Launching 'Dial' [Sep 17 15:50:02] VERBOSE[17850][C-00000026] pbx.c: Executing [+441865251758@voipin:24] Dial("PJSIP/lcr-03-0000004a", "PJSIP/+441865251758@coltukpub") in new stack [Sep 17 15:50:02] DEBUG[17850][C-00000026] stasis.c: Creating topic. name: channel:1568731802.112, detail: [Sep 17 15:50:02] DEBUG[17850][C-00000026] stasis.c: Topic 'channel:1568731802.112': 0x7f9164001f90 created [Sep 17 15:50:02] DEBUG[17850][C-00000026] stasis.c: Creating topic. name: cache:160/channel:1568731802.112, detail: [Sep 17 15:50:02] DEBUG[17850][C-00000026] stasis.c: Topic 'cache:160/channel:1568731802.112': 0x7f916400eac0 created [Sep 17 15:50:02] DEBUG[17850][C-00000026] channel.c: Channel 0x7f9164006590 'PJSIP/coltukpub-0000004b' allocated [Sep 17 15:50:02] VERBOSE[17850][C-00000026] app_dial.c: Called PJSIP/+441865251758@coltukpub [Sep 17 15:50:02] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:02] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting write format path: alaw -> alaw [Sep 17 15:50:02] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting read format path: alaw -> alaw [Sep 17 15:50:02] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f9148022e50' [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Allocated port 13218 for RTP instance '0x7f9148022e50' [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:13218 (13218) for RTP instance '0x7f9148022e50' [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.30' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '10.200.20.30' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port ''. [Sep 17 15:50:02] DEBUG[17845] rtp_engine.c: RTP instance '0x7f9148022e50' is setup and ready to go [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting 'uk-carrier-01-thn' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host 'uk-carrier-01-thn' and port ''. [Sep 17 15:50:02] DEBUG[17845] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f9148022e50' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:02] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:02] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:02] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:02] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:02] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:02] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current inv state is CALLING [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current inv state is CALLING [Sep 17 15:50:02] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:02] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:02] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:02] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:02] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 100/INVITE/cseq=19204 (rdata0x7f911400a498) [Sep 17 15:50:02] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: The current inv state is CALLING [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:02] DEBUG[17845] res_pjsip_session.c: Response is 100 Trying [Sep 17 15:50:02] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:50:02] VERBOSE[17857] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:50:03] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:03] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:03] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:03] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:03] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 183/INVITE/cseq=19204 (rdata0x7f911400a498) [Sep 17 15:50:03] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is RX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Response is 183 Session Progress [Sep 17 15:50:03] VERBOSE[17850][C-00000026] app_dial.c: PJSIP/coltukpub-0000004b is making progress passing it to PJSIP/lcr-03-0000004a [Sep 17 15:50:03] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:03] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 2 (In use) [Sep 17 15:50:03] VERBOSE[17850][C-00000026] app_dial.c: PJSIP/coltukpub-0000004b is making progress passing it to PJSIP/lcr-03-0000004a [Sep 17 15:50:03] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:03] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 2 (In use) [Sep 17 15:50:03] DEBUG[3709] app_queue.c: Device 'PJSIP/lcr-03' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:03] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148014090' [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.20.163' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.20.163' and port ''. [Sep 17 15:50:03] DEBUG[17845] acl.c: For destination '10.200.20.163', our source address is '10.200.20.30'. [Sep 17 15:50:03] DEBUG[17845] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f9148014090' [Sep 17 15:50:03] VERBOSE[17845] res_rtp_asterisk.c: 0x7f9148016390 -- Strict RTP learning after remote address set to: 10.200.20.163:10598 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f912eeb10b0 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f912eeb10b0 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 0 (0x7f91480447b8) from 0x7f912eeb10b0 to 0x7f9148014268 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 8 (0x7f9148035228) from 0x7f912eeb10b0 to 0x7f9148014268 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 101 (0x7f9148044808) from 0x7f912eeb10b0 to 0x7f9148014268 [Sep 17 15:50:03] DEBUG[17845] channel.c: Channel PJSIP/lcr-03-0000004a setting read format path: alaw -> alaw [Sep 17 15:50:03] DEBUG[17845] channel.c: Channel PJSIP/lcr-03-0000004a setting write format path: alaw -> alaw [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:03] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:03] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:03] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:03] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 183/INVITE/cseq=19204 (rdata0x7f911400a498) [Sep 17 15:50:03] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is RX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:03] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '84.14.246.196' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '84.14.246.196' and port ''. [Sep 17 15:50:03] DEBUG[17845] acl.c: For destination '84.14.246.196', our source address is '193.203.210.228'. [Sep 17 15:50:03] VERBOSE[17850][C-00000026] app_dial.c: PJSIP/coltukpub-0000004b is making progress passing it to PJSIP/lcr-03-0000004a [Sep 17 15:50:03] DEBUG[17845] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f9148022e50' [Sep 17 15:50:03] VERBOSE[17845] res_rtp_asterisk.c: 0x7f9148023dd0 -- Strict RTP learning after remote address set to: 84.14.246.196:60466 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f912eeb0e80 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7f912eeb0e80 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 8 (0x7f9148009858) from 0x7f912eeb0e80 to 0x7f9148023028 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 18 (0x7f914801b6a8) from 0x7f912eeb0e80 to 0x7f9148023028 [Sep 17 15:50:03] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 101 (0x7f914803e5f8) from 0x7f912eeb0e80 to 0x7f9148023028 [Sep 17 15:50:03] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 2 (In use) [Sep 17 15:50:03] DEBUG[17845] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:03] DEBUG[17845] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:03] DEBUG[17846] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17846] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:03] DEBUG[17846] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:03] DEBUG[17846] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:03] DEBUG[17846] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:03] DEBUG[17846] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:03] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:03] DEBUG[17846] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] VERBOSE[17850][C-00000026] app_dial.c: PJSIP/coltukpub-0000004b is making progress passing it to PJSIP/lcr-03-0000004a [Sep 17 15:50:03] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:03] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 2 (In use) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Sep 17 15:50:03] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:03] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:03] DEBUG[17845] res_pjsip_session.c: The current inv state is EARLY [Sep 17 15:50:03] VERBOSE[17850][C-00000026] res_rtp_asterisk.c: 0x7f9148023dd0 -- Strict RTP switching to RTP target address 84.14.246.196:60466 as source [Sep 17 15:50:03] DEBUG[17850][C-00000026] res_rtp_asterisk.c: Ooh, format changed from none to alaw [Sep 17 15:50:03] DEBUG[17850][C-00000026] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x7f9148014090' [Sep 17 15:50:03] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:50:03] VERBOSE[17865] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:50:05] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:05] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:05] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:05] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:05] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 200/INVITE/cseq=19204 (rdata0x7f911400a498) [Sep 17 15:50:05] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current inv state is CONNECTING [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is RX_MSG [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:05] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:05] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:05] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:05] VERBOSE[17850][C-00000026] app_dial.c: PJSIP/coltukpub-0000004b answered PJSIP/lcr-03-0000004a [Sep 17 15:50:05] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - coltukpub [Sep 17 15:50:05] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:05] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:05] DEBUG[3648] devicestate.c: Changing state for PJSIP/coltukpub - state 2 (In use) [Sep 17 15:50:05] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:05] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting write format path: alaw -> alaw [Sep 17 15:50:05] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting read format path: alaw -> alaw [Sep 17 15:50:05] DEBUG[17850][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:05] DEBUG[3709] app_queue.c: Device 'PJSIP/coltukpub' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 17 15:50:05] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:05] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:05] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:05] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:05] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:05] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 2 (In use) [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TX_MSG [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: Method is INVITE, Response is 200 OK [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:05] DEBUG[17846] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:05] DEBUG[17846] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:05] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:05] DEBUG[17846] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:05] DEBUG[17846] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:05] DEBUG[17846] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:05] DEBUG[17846] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:05] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The current inv state is CONNECTING [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: Source of transaction state change is TX_MSG [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:05] DEBUG[17846] res_pjsip_session.c: The current inv state is CONNECTING [Sep 17 15:50:05] DEBUG[17850][C-00000026] stasis.c: Creating topic. name: bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de, detail: [Sep 17 15:50:05] DEBUG[17850][C-00000026] stasis.c: Topic 'bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de': 0x7f916400d470 created [Sep 17 15:50:05] DEBUG[17850][C-00000026] stasis.c: Creating topic. name: cache:161/bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de, detail: [Sep 17 15:50:05] DEBUG[17850][C-00000026] stasis.c: Topic 'cache:161/bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de': 0x7f9164001350 created [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as two channels are required [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:05] DEBUG[17850][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Must have two channels. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology constructor [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology start [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is joining [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: pushing 0x7f916400fe80(PJSIP/coltukpub-0000004b) [Sep 17 15:50:05] VERBOSE[17866][C-00000026] bridge_channel.c: Channel PJSIP/coltukpub-0000004b joined 'simple_bridge' basic-bridge [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as two channels are required [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:05] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Must have two channels. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is joining simple_bridge technology [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fb60(PJSIP/lcr-03-0000004a) is joining [Sep 17 15:50:05] DEBUG[17866][C-00000026] channel.c: Dropping duplicate answer! [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: pushing 0x7f916400fb60(PJSIP/lcr-03-0000004a) [Sep 17 15:50:05] VERBOSE[17850][C-00000026] bridge_channel.c: Channel PJSIP/lcr-03-0000004a joined 'simple_bridge' basic-bridge [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/coltukpub-0000004b' and 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:05] DEBUG[3657] cdr.c: Finalized CDR for PJSIP/coltukpub-0000004b - start 1568731802.365575 answer 1568731805.369879 end 1568731805.370442 dispo ANSWERED [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_dahdi has less preference than native_rtp (90 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology simple_bridge has less preference than native_rtp (50 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Chose bridge technology native_rtp [Sep 17 15:50:05] VERBOSE[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: switching from simple_bridge technology to native_rtp [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling native_rtp technology constructor [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: moving 0x7f916400fe80(PJSIP/coltukpub-0000004b) to dummy bridge temporarily [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is leaving simple_bridge technology (dummy) [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology stop [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fb60(PJSIP/lcr-03-0000004a) is joining native_rtp technology [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Channel 'PJSIP/lcr-03-0000004a' is joining bridge tech [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Attaching hook data 0x7f916400e410 to 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is joining native_rtp technology [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Channel 'PJSIP/coltukpub-0000004b' is joining bridge tech [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Attaching hook data 0x7f9164001190 to 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Tech starting 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' with target 'none' [Sep 17 15:50:05] VERBOSE[17850][C-00000026] bridge_native_rtp.c: Locally RTP bridged 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' in stack [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling native_rtp technology start [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology destructor [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_dahdi has less preference than native_rtp (90 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology simple_bridge has less preference than native_rtp (50 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Chose bridge technology native_rtp [Sep 17 15:50:05] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi has less preference than native_rtp (90 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology simple_bridge has less preference than native_rtp (50 <= 90). Skipping. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology native_rtp [Sep 17 15:50:05] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:05] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:05] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:05] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:05] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:05] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Request msg ACK/cseq=498748188 (rdata0x7f911400a498) [Sep 17 15:50:05] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The transaction state change event is USER [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current inv state is CONNECTING [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event RX_MSG [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Received request [Sep 17 15:50:05] DEBUG[17845] res_pjsip_session.c: Method is ACK [Sep 17 15:50:05] VERBOSE[17850][C-00000026] res_rtp_asterisk.c: 0x7f9148016390 -- Strict RTP switching to RTP target address 10.200.20.163:10598 as source [Sep 17 15:50:05] DEBUG[3679] res_pjsip_registrar.c: Woke up at 1568731805 Interval: 30 [Sep 17 15:50:05] DEBUG[3679] res_pjsip_registrar.c: Expiring 0 contacts [Sep 17 15:50:08] VERBOSE[17850][C-00000026] res_rtp_asterisk.c: 0x7f9148016390 -- Strict RTP learning complete - Locking on source address 10.200.20.163:10598 [Sep 17 15:50:08] VERBOSE[17866][C-00000026] res_rtp_asterisk.c: 0x7f9148023dd0 -- Strict RTP learning complete - Locking on source address 84.14.246.196:60466 [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '193.203.210.228:13219' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '193.203.210.228' and port '13219'. [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '84.14.246.196:60467' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '84.14.246.196' and port '60467'. [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '10.200.20.30:16147' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '10.200.20.30' and port '16147'. [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '10.200.20.163:10599' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '10.200.20.163' and port '10599'. [Sep 17 15:50:08] DEBUG[17850][C-00000026] res_rtp_asterisk.c: Got RTCP report of 64 bytes from 10.200.20.163:10599 [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '10.200.20.163:10599' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '10.200.20.163' and port '10599'. [Sep 17 15:50:08] DEBUG[3708] netsock2.c: Splitting '10.200.20.30:16147' into... [Sep 17 15:50:08] DEBUG[3708] netsock2.c: ...host '10.200.20.30' and port '16147'. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg INVITE/cseq=206025 (rdata0x7f911400a498) [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: UDPTL initialized on session for PJSIP/coltukpub-0000004b [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: T.38 state changed to '2' from '0' on channel 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: Deferring incoming SDP stream on PJSIP/coltukpub-0000004b for peer re-invite [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:10] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:10] VERBOSE[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: switching from native_rtp technology to simple_bridge [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology constructor [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: moving 0x7f916400fb60(PJSIP/lcr-03-0000004a) to dummy bridge temporarily [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: moving 0x7f916400fe80(PJSIP/coltukpub-0000004b) to dummy bridge temporarily [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fb60(PJSIP/lcr-03-0000004a) is leaving native_rtp technology (dummy) [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Channel 'PJSIP/lcr-03-0000004a' is leaving bridge tech [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Detaching hook data 0x7f9164010bf8 from 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Tech stopping 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' with target 'none' [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Discontinued RTP bridging of 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' - media will flow through Asterisk core [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7f916400e410 [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is leaving native_rtp technology (dummy) [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Channel 'PJSIP/coltukpub-0000004b' is leaving bridge tech [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Detaching hook data 0x7f9164011878 from 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Destroying channel tech_pvt data 0x7f9164001190 [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling native_rtp technology stop [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fb60(PJSIP/lcr-03-0000004a) is joining simple_bridge technology [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting write format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting read format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is joining simple_bridge technology [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting write format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/lcr-03-0000004a setting read format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology start [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling native_rtp technology destructor [Sep 17 15:50:10] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to coltukpub [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:10] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:10] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400e818) [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Delay sending reinvite to coltukpub because of outstanding transaction... [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Delay sending reinvite to coltukpub because of outstanding transaction... [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17850][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:10] DEBUG[17850][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: UDPTL initialized on session for PJSIP/lcr-03-0000004a [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: T.38 state changed to '1' from '0' on channel 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17850][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:10] DEBUG[17850][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:10] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to lcr-03 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '10.200.208.128' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '10.200.208.128' is 'UDP' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '10.200.208.128' is an IP address, skipping resolution [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Response msg 100/INVITE/cseq=973 (rdata0x7f911400a498) [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Response is 100 trying -- your call is important to us [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 491/INVITE/cseq=19205 (rdata0x7f911400a498) [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Response is 491 Request Pending [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:10] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Response msg 200/INVITE/cseq=973 (rdata0x7f911400a498) [Sep 17 15:50:10] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Applying negotiated SDP media stream 'image' using image SDP handler [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.20.163' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.20.163' and port ''. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Applied negotiated SDP media stream 'image' using image SDP handler [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '10.200.208.128' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '10.200.208.128' is 'UDP' [Sep 17 15:50:10] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '10.200.208.128' is an IP address, skipping resolution [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:10] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:10] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:10] DEBUG[17845] res_pjsip_t38.c: T.38 state changed to '3' from '1' on channel 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:10] DEBUG[17850][C-00000026] channel.c: Dropping duplicate answer! [Sep 17 15:50:10] ERROR[17845] res_pjsip_t38.c: Failed to negotiate parameters for reinvite on channel 'PJSIP/coltukpub-0000004b' (No pending session media). [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] VERBOSE[17850][C-00000026] udptl.c: UDPTL (PJSIP/lcr-03-0000004a): packet from 10.200.20.163:10598 (seq 0, len 6) [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:10] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] VERBOSE[17850][C-00000026] udptl.c: UDPTL (PJSIP/lcr-03-0000004a): packet from 10.200.20.163:10598 (seq 1, len 8) [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:11] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:11] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:11] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:11] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg INVITE/cseq=206025 (rdata0x7f911400a498) [Sep 17 15:50:11] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:11] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:12] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:12] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:12] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:12] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg INVITE/cseq=206025 (rdata0x7f911400a498) [Sep 17 15:50:12] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:12] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[3708] netsock2.c: Splitting '193.203.210.228:13219' into... [Sep 17 15:50:13] DEBUG[3708] netsock2.c: ...host '193.203.210.228' and port '13219'. [Sep 17 15:50:13] DEBUG[3708] netsock2.c: Splitting '84.14.246.196:60467' into... [Sep 17 15:50:13] DEBUG[3708] netsock2.c: ...host '84.14.246.196' and port '60467'. [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[3665] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision timer expired. [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' sending delayed INVITE request. [Sep 17 15:50:13] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to coltukpub [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:13] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:13] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:13] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:13] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400d868) [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:13] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:13] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:13] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:13] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:13] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 491/INVITE/cseq=19206 (rdata0x7f911400a498) [Sep 17 15:50:13] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:13] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:13] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Response is 491 Request Pending [Sep 17 15:50:13] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision. [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:13] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:14] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:14] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:14] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:14] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg INVITE/cseq=206025 (rdata0x7f911400a498) [Sep 17 15:50:14] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:14] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[3665] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision timer expired. [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' sending delayed INVITE request. [Sep 17 15:50:15] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to coltukpub [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9104023448) [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104023448 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:15] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 491/INVITE/cseq=19207 (rdata0x7f911400a498) [Sep 17 15:50:15] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104023448 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Response is 491 Request Pending [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision. [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[17845] res_pjsip_t38.c: Automatically rejecting T.38 request on channel 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_t38.c: T.38 state changed to '4' from '2' on channel 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg INVITE/cseq=206025 (rdata0x7f914800a828) [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.196' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.196' and port ''. [Sep 17 15:50:15] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f912eeb1150 [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x55f34b9a1248) from 0x7f912eeb1150 to 0x7f912eeb1150 [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Copying rx payload mapping 0 (0x55f34b9a1248) from 0x7f912eeb1150 to 0x7f9148023028 [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 0 (0x55f34b9a1248) from 0x7f912eeb1150 to 0x7f9148023028 [Sep 17 15:50:15] DEBUG[17845] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: ulaw -> alaw [Sep 17 15:50:15] DEBUG[17845] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> ulaw [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Media stream 'audio' handled by audio [Sep 17 15:50:15] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:15] DEBUG[17845] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148022e50' [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.196' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.196' and port ''. [Sep 17 15:50:15] DEBUG[17845] acl.c: For destination '84.14.246.196', our source address is '193.203.210.228'. [Sep 17 15:50:15] DEBUG[17845] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f9148022e50' [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f912eeb0dc0 [Sep 17 15:50:15] DEBUG[17845] rtp_engine.c: Copying tx payload mapping 0 (0x55f34b9a1248) from 0x7f912eeb0dc0 to 0x7f9148023028 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Sep 17 15:50:15] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9104036238) [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9104036238) [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Received request [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:15] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:15] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:15] DEBUG[17866][C-00000026] chan_pjsip.c: Oooh, got a frame with format of alaw on channel 'PJSIP/coltukpub-0000004b' when we're sending 'ulaw', switching to match [Sep 17 15:50:15] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting write format path: alaw -> alaw [Sep 17 15:50:15] DEBUG[17866][C-00000026] channel.c: Channel PJSIP/coltukpub-0000004b setting read format path: alaw -> alaw [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de'. Checking compatability for channels 'PJSIP/lcr-03-0000004a' and 'PJSIP/coltukpub-0000004b' [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge_native_rtp.c: Bridge 'fd1f88d1-d72c-48cb-8d30-296a53f344de' can not use native RTP bridge as it was forbidden while getting details [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Sep 17 15:50:15] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/lcr-03-0000004a' is not DAHDI. [Sep 17 15:50:15] DEBUG[17866][C-00000026] dahdi/bridge_native_dahdi.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: Cannot use native DAHDI. Channel 'PJSIP/lcr-03-0000004a' not compatible. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge technology softmix does not have any capabilities we want. [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Chose bridge technology simple_bridge [Sep 17 15:50:15] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is already using the new technology. [Sep 17 15:50:15] DEBUG[17850][C-00000026] chan_pjsip.c: Channel PJSIP/lcr-03-0000004a stream 0 is of type 'image', not audio! [Sep 17 15:50:15] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:15] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:15] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:15] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:15] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Request msg ACK/cseq=206025 (rdata0x7f911400a498) [Sep 17 15:50:15] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The transaction state change event is USER [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:15] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' INVITE delay check. tsx-state:Terminated [Sep 17 15:50:18] DEBUG[3665] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' re-INVITE collision timer expired. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' sending delayed INVITE request. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to coltukpub [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 193.203.210.228:5060 (this may be re-written again later) [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '193.203.210.228' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port ''. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9104036238) [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 100/INVITE/cseq=19208 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f9104036238) [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 100 Trying [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' INVITE delay check. tsx-state:Proceeding [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 200/INVITE/cseq=19208 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: Setting 0x7f916400fe80(PJSIP/coltukpub-0000004b) state from:0 to:1 [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: pulling 0x7f916400fe80(PJSIP/coltukpub-0000004b) [Sep 17 15:50:18] VERBOSE[17866][C-00000026] bridge_channel.c: Channel PJSIP/coltukpub-0000004b left 'simple_bridge' basic-bridge [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fe80(PJSIP/coltukpub-0000004b) is leaving simple_bridge technology [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: dissolving bridge with cause 58(Bearer capability not available) [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: Setting 0x7f916400fb60(PJSIP/lcr-03-0000004a) state from:0 to:2 [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: queueing action type:13 sub:1001 [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: T.38 terminate simulated to bridge fd1f88d1-d72c-48cb-8d30-296a53f344de because PJSIP/coltukpub-0000004b left. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[3657] cdr.c: Finalized CDR for PJSIP/lcr-03-0000004a - start 1568731802.363419 answer 1568731805.369951 end 1568731818.120991 dispo ANSWERED [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is dissolved, not performing smart bridge operation. [Sep 17 15:50:18] DEBUG[17866][C-00000026] bridge_channel.c: Channel PJSIP/coltukpub-0000004b simulating T.38 terminate for bridge end. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: pulling 0x7f916400fb60(PJSIP/lcr-03-0000004a) [Sep 17 15:50:18] DEBUG[17866][C-00000026] channel.c: Channel 0x7f9164006590 'PJSIP/coltukpub-0000004b' hanging up. Refs: 2 [Sep 17 15:50:18] VERBOSE[17850][C-00000026] bridge_channel.c: Channel PJSIP/lcr-03-0000004a left 'simple_bridge' basic-bridge [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge_channel.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: 0x7f916400fb60(PJSIP/lcr-03-0000004a) is leaving simple_bridge technology [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge_channel.c: T.38 terminate simulated to bridge fd1f88d1-d72c-48cb-8d30-296a53f344de because PJSIP/lcr-03-0000004a left. [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de is dissolved, not performing smart bridge operation. [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge_channel.c: Channel PJSIP/lcr-03-0000004a simulating T.38 terminate for bridge end. [Sep 17 15:50:18] DEBUG[17846] res_pjsip_t38.c: T.38 state changed to '0' from '3' on channel 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:18] DEBUG[17846] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f9148014090' [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to lcr-03 [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: Method is INVITE [Sep 17 15:50:18] DEBUG[17846] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '10.200.208.128' [Sep 17 15:50:18] DEBUG[17846] res_pjsip/pjsip_resolver.c: Transport type for target '10.200.208.128' is 'UDP' [Sep 17 15:50:18] DEBUG[17846] res_pjsip/pjsip_resolver.c: Target '10.200.208.128' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17846] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 10.200.20.30:5060 (this may be re-written again later) [Sep 17 15:50:18] DEBUG[17846] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:18] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:18] DEBUG[17846] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' INVITE delay check. tsx-state:Terminated [Sep 17 15:50:18] DEBUG[17846] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[17846] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[17846] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[17846] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] rtp_engine.c: Destroyed RTP instance '0x7f9148022e50' [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03(PJSIP/lcr-03-0000004a)' [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400f868) [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400f868 [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Method is BYE [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:18] DEBUG[17846] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '84.14.246.209' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '84.14.246.209' is 'UDP' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '84.14.246.209' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port ''. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: actually destroying basic bridge, nobody wants it anymore [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling basic bridge destructor [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology stop [Sep 17 15:50:18] DEBUG[17850][C-00000026] bridge.c: Bridge fd1f88d1-d72c-48cb-8d30-296a53f344de: calling simple_bridge technology destructor [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub(PJSIP/coltukpub-0000004b)' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17850][C-00000026] stasis.c: Destroying topic. name: cache:161/bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de, detail: [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f91040dbff8 [Sep 17 15:50:18] DEBUG[17850][C-00000026] stasis.c: Topic 'cache:161/bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de': 0x7f9164001350 destroyed [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:18] DEBUG[17850][C-00000026] stasis.c: Destroying topic. name: bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de, detail: [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17850][C-00000026] stasis.c: Topic 'bridge:fd1f88d1-d72c-48cb-8d30-296a53f344de': 0x7f916400d470 destroyed [Sep 17 15:50:18] DEBUG[17845] channel.c: Channel 0x7f9164006590 'PJSIP/coltukpub-0000004b' destroying [Sep 17 15:50:18] DEBUG[3657] cdr.c: CDR for PJSIP/coltukpub-0000004b is dialed and has no Party B; discarding [Sep 17 15:50:18] DEBUG[3636] threadpool.c: Increasing threadpool stasis/pool's size by 1 [Sep 17 15:50:18] DEBUG[17845] stasis.c: Destroying topic. name: cache:160/channel:1568731802.112, detail: [Sep 17 15:50:18] DEBUG[17845] stasis.c: Topic 'cache:160/channel:1568731802.112': 0x7f916400eac0 destroyed [Sep 17 15:50:18] DEBUG[17845] stasis.c: Destroying topic. name: channel:1568731802.112, detail: [Sep 17 15:50:18] DEBUG[17845] stasis.c: Topic 'channel:1568731802.112': 0x7f9164001f90 destroyed [Sep 17 15:50:18] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - coltukpub [Sep 17 15:50:18] DEBUG[3648] devicestate.c: Changing state for PJSIP/coltukpub - state 1 (Not in use) [Sep 17 15:50:18] DEBUG[17850][C-00000026] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Sep 17 15:50:18] DEBUG[17850][C-00000026] pbx.c: Spawn extension (voipin,+441865251758,24) exited non-zero on 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:18] DEBUG[3709] app_queue.c: Device 'PJSIP/coltukpub' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 17 15:50:18] VERBOSE[17850][C-00000026] pbx.c: Spawn extension (voipin, +441865251758, 24) exited non-zero on 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:18] DEBUG[17850][C-00000026] channel.c: Soft-Hanging (0x10) up channel 'PJSIP/lcr-03-0000004a' [Sep 17 15:50:18] DEBUG[17850][C-00000026] channel.c: Channel 0x7f914801ff80 'PJSIP/lcr-03-0000004a' hanging up. Refs: 2 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Delay sending BYE to lcr-03 because of outstanding transaction... [Sep 17 15:50:18] DEBUG[17845] channel.c: Channel 0x7f914801ff80 'PJSIP/lcr-03-0000004a' destroying [Sep 17 15:50:18] DEBUG[3636] threadpool.c: Increasing threadpool stasis/pool's size by 1 [Sep 17 15:50:18] DEBUG[17845] stasis.c: Destroying topic. name: cache:159/channel:1568731802.111, detail: [Sep 17 15:50:18] DEBUG[17845] stasis.c: Topic 'cache:159/channel:1568731802.111': 0x7f9148022720 destroyed [Sep 17 15:50:18] DEBUG[17845] stasis.c: Destroying topic. name: channel:1568731802.111, detail: [Sep 17 15:50:18] DEBUG[17845] stasis.c: Topic 'channel:1568731802.111': 0x7f914801aba0 destroyed [Sep 17 15:50:18] DEBUG[3648] devicestate.c: No provider found, checking channel drivers for PJSIP - lcr-03 [Sep 17 15:50:18] DEBUG[3648] devicestate.c: Changing state for PJSIP/lcr-03 - state 1 (Not in use) [Sep 17 15:50:18] DEBUG[3709] app_queue.c: Device 'PJSIP/lcr-03' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 17 15:50:18] DEBUG[3657] stasis.c: Creating topic. name: channel:1568731818.113, detail: [Sep 17 15:50:18] DEBUG[3657] stasis.c: Topic 'channel:1568731818.113': 0x7f9118002b10 created [Sep 17 15:50:18] DEBUG[3657] stasis.c: Creating topic. name: cache:162/channel:1568731818.113, detail: [Sep 17 15:50:18] DEBUG[3657] stasis.c: Topic 'cache:162/channel:1568731818.113': 0x7f911800b850 created [Sep 17 15:50:18] DEBUG[3657] stasis.c: Destroying topic. name: cache:162/channel:1568731818.113, detail: [Sep 17 15:50:18] DEBUG[3657] stasis.c: Topic 'cache:162/channel:1568731818.113': 0x7f911800b850 destroyed [Sep 17 15:50:18] DEBUG[3657] stasis.c: Destroying topic. name: channel:1568731818.113, detail: [Sep 17 15:50:18] DEBUG[3657] stasis.c: Topic 'channel:1568731818.113': 0x7f9118002b10 destroyed [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Response msg 100/INVITE/cseq=974 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f910400f868) [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400f868 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Proceeding [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 100 trying -- your call is important to us [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'lcr-03()' INVITE delay check. tsx-state:Proceeding [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Response msg 200/INVITE/cseq=974 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '10.200.208.128' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '10.200.208.128' is 'UDP' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '10.200.208.128' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400f868 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'lcr-03()' INVITE delay check. tsx-state:Terminated [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Endpoint 'lcr-03()' sending delayed BYE request. [Sep 17 15:50:18] DEBUG[17845] rtp_engine.c: Destroyed RTP instance '0x7f9148014090' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Method is BYE [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '10.200.208.128' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Transport type for target '10.200.208.128' is 'UDP' [Sep 17 15:50:18] DEBUG[17845] res_pjsip/pjsip_resolver.c: Target '10.200.208.128' is an IP address, skipping resolution [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.208.128' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port ''. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_nat.c: Request is being sent to local address, skipping NAT manipulation [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[17845] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Calling [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is TX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is CONFIRMED [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '84.14.246.209:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '84.14.246.209' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '193.203.210.228:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '193.203.210.228' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104050798 for Response msg 200/BYE/cseq=19209 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/coltukpub-00000086 associated with dialog dlg0x7f9104050798 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f91040dbff8 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: BYE received final response code 200 [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.208.128:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.208.128' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] netsock2.c: Splitting '10.200.20.30:5060' into... [Sep 17 15:50:18] DEBUG[3665] netsock2.c: ...host '10.200.20.30' and port '5060'. [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f9104061588 for Response msg 200/BYE/cseq=975 (rdata0x7f911400a498) [Sep 17 15:50:18] DEBUG[3665] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000023 associated with dialog dlg0x7f9104061588 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: There is no transaction involved in this state change [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Source of transaction state change is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current transaction state is Completed [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The transaction state change event is RX_MSG [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Received response [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: Response is 200 OK [Sep 17 15:50:18] DEBUG[17845] res_pjsip_session.c: BYE received final response code 200 [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The state change pertains to the endpoint 'coltukpub()' [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f91040dbff8 [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The transaction state change event is TIMER [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:23] DEBUG[17845] res_pjsip_session.c: Destroying SIP session with endpoint coltukpub [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The state change pertains to the endpoint 'lcr-03()' [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f9104036238 [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The transaction state change event is TIMER [Sep 17 15:50:23] DEBUG[3665] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:23] DEBUG[17845] res_pjsip_session.c: Destroying SIP session with endpoint lcr-03 [Sep 17 15:50:35] DEBUG[3679] res_pjsip_registrar.c: Woke up at 1568731835 Interval: 30 [Sep 17 15:50:35] DEBUG[3679] res_pjsip_registrar.c: Expiring 0 contacts [Sep 17 15:50:38] DEBUG[17871] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:50:38] DEBUG[3636] threadpool.c: Destroying worker thread 80 [Sep 17 15:50:38] DEBUG[17872] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:50:38] DEBUG[3636] threadpool.c: Destroying worker thread 81 [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: inv_session 0x7f914800a068 has no ast session [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400e818 [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: The transaction state change event is TIMER [Sep 17 15:50:42] DEBUG[3665] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: inv_session 0x7f914800a068 has no ast session [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f910400d868 [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: The transaction state change event is TIMER [Sep 17 15:50:45] DEBUG[3665] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: inv_session 0x7f914800a068 has no ast session [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f9104023448 [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: The current transaction state is Terminated [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: The transaction state change event is TIMER [Sep 17 15:50:47] DEBUG[3665] res_pjsip_session.c: The current inv state is DISCONNCTD [Sep 17 15:50:50] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:50:50] VERBOSE[17926] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:51:02] DEBUG[17848] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:51:02] DEBUG[17849] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:51:02] DEBUG[17847] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:51:02] DEBUG[3662] threadpool.c: Destroying worker thread 79 [Sep 17 15:51:02] DEBUG[3662] threadpool.c: Destroying worker thread 77 [Sep 17 15:51:02] DEBUG[3662] threadpool.c: Destroying worker thread 78 [Sep 17 15:51:02] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:51:02] VERBOSE[17936] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:51:04] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:51:04] VERBOSE[17943] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:51:05] DEBUG[3679] res_pjsip_registrar.c: Woke up at 1568731865 Interval: 30 [Sep 17 15:51:05] DEBUG[3679] res_pjsip_registrar.c: Expiring 0 contacts [Sep 17 15:51:18] DEBUG[17846] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:51:18] DEBUG[3662] threadpool.c: Destroying worker thread 76 [Sep 17 15:51:23] DEBUG[17845] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 17 15:51:23] DEBUG[3662] threadpool.c: Destroying worker thread 75 [Sep 17 15:51:35] DEBUG[3679] res_pjsip_registrar.c: Woke up at 1568731895 Interval: 30 [Sep 17 15:51:35] DEBUG[3679] res_pjsip_registrar.c: Expiring 0 contacts [Sep 17 15:51:50] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:51:50] VERBOSE[18008] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:52:02] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:52:02] VERBOSE[18026] asterisk.c: Remote UNIX connection disconnected [Sep 17 15:52:03] VERBOSE[3643] asterisk.c: Remote UNIX connection [Sep 17 15:52:03] VERBOSE[18037] asterisk.c: Remote UNIX connection disconnected