-- Executing [7XXXXXXXXXX@dial-ip:1] Dial("Local/8XXXXXXXXXX@dial-ip-00000004;2", "PJSIP/7XXXXXXXXXX@mango2,,T") in new stack [Sep 11 10:40:57] DEBUG[15688][C-00000004]: rtp_engine.c:1158 ast_rtp_instance_early_bridge_make_compatible: Can't find native functions for channel 'Local/8XXXXXXXXXX@dial-ip-00000004;2' [Sep 11 10:40:57] DEBUG[15688][C-00000004]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_name from Local/8XXXXXXXXXX@dial-ip-00000004;2 to PJSIP/mango2-00000006. [Sep 11 10:40:57] DEBUG[15688][C-00000004]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_num from Local/8XXXXXXXXXX@dial-ip-00000004;2 to PJSIP/mango2-00000006. [Sep 11 10:40:57] DEBUG[15688][C-00000004]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_uid from Local/8XXXXXXXXXX@dial-ip-00000004;2 to PJSIP/mango2-00000006. [Sep 11 10:40:57] DEBUG[15328]: rtp_engine.c:425 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x8dc3e6c' [Sep 11 10:40:57] DEBUG[15328]: res_rtp_asterisk.c:2489 ast_rtp_new: Allocated port 6336 for RTP instance '0x8dc3e6c' [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 ICE session created, comp_cnt=2, role is Unknown agent [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 0 added: comp_id=1, type=host, foundation=Hb213f2a9, addr=XXX.XX.XXX.XXX:6336, base=XXX.XX.XXX.XXX:6336, prio=0x7effffff (2130706431) [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 1 added: comp_id=1, type=host, foundation=Hc0a800fe, addr=192.168.0.254:6336, base=192.168.0.254:6336, prio=0x7effffff (2130706431) [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 2 added: comp_id=1, type=host, foundation=Hac100001, addr=172.16.0.1:6336, base=172.16.0.1:6336, prio=0x7effffff (2130706431) [Sep 11 10:40:57] DEBUG[15328]: rtp_engine.c:434 ast_rtp_instance_new: RTP instance '0x8dc3e6c' is setup and ready to go [Sep 11 10:40:57] DEBUG[15328]: res_rtp_asterisk.c:4721 ast_rtp_prop_set: Setup RTCP on RTP instance '0x8dc3e6c' [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 3 added: comp_id=2, type=host, foundation=Hb213f2a9, addr=XXX.XX.XXX.XXX:6337, base=XXX.XX.XXX.XXX:6337, prio=0x7efffffe (2130706430) [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 4 added: comp_id=2, type=host, foundation=Hc0a800fe, addr=192.168.0.254:6337, base=192.168.0.254:6337, prio=0x7efffffe (2130706430) [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Candidate 5 added: comp_id=2, type=host, foundation=Hac100001, addr=172.16.0.1:6337, base=172.16.0.1:6337, prio=0x7efffffe (2130706430) [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : icess0x84ddf34 Destroying ICE session 0x84ddf34 [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : ice_session.c ICE session 0x84ddf34 destroyed -- Called PJSIP/7XXXXXXXXXX@mango2 [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : resolver.c ...Transmitting 53 bytes to NS 0 (10.0.1.254:53): DNS SRV query for _sip._udp.vpbx400034059.mangosip.ru: Success [Sep 11 10:40:57] DEBUG[15688][C-00000004]: channel.c:5490 set_format: Channel PJSIP/mango2-00000006 setting read format path: gsm -> gsm [Sep 11 10:40:57] DEBUG[15688][C-00000004]: channel.c:5490 set_format: Channel PJSIP/mango2-00000006 setting write format path: gsm -> gsm [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2333 session_inv_on_state_changed: Source of transaction state change is TX_MSG [Sep 11 10:40:57] DEBUG[15686][C-00000004]: chan_iax2.c:5759 iax2_indicate: Callno 10193: Config blocked sending control frame 22. [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2263 handle_outgoing: Sending request [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2263 handle_outgoing: Sending request [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE [Sep 11 10:40:57] DEBUG[15333]: pjsip:0 : _sip._udp.vpbx DNS SRV resolution failed for _sip._udp.vpbx400034059.mangosip.ru, trying resolving A record for vpbx400034059.mangosip.ru [Sep 11 10:40:57] DEBUG[15333]: res_pjsip_multihomed.c:157 multihomed_on_tx_message: Re-wrote Contact URI host/port to XXX.XX.XXX.XXX:5060 [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2220 handle_incoming: Received response [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2204 handle_incoming_response: Response is 100 Trying [Sep 11 10:40:57] DEBUG[15327]: threadpool.c:508 grow: Increasing threadpool SIP's size by 5 [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:1476 outbound_invite_auth: Initial INVITE is being challenged. [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE [Sep 11 10:40:57] DEBUG[15328]: pjsip:0 : dlg0x84651bc ....Module NAT already registered as dialog usage, updating the data (nil) [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_multihomed.c:157 multihomed_on_tx_message: Re-wrote Contact URI host/port to XXX.XX.XXX.XXX:5060 [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2263 handle_outgoing: Sending request [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2220 handle_incoming: Received response [Sep 11 10:40:57] DEBUG[15328]: res_pjsip_session.c:2204 handle_incoming_response: Response is 401 Unauthorized ... [Sep 11 10:41:38] DEBUG[15356][C-00000004]: chan_iax2.c:10951 socket_process_helper: Immediately destroying 10193, having received hangup [Sep 11 10:41:38] DEBUG[15686][C-00000004]: channel.c:2682 ast_hangup: Hanging up channel 'Local/8XXXXXXXXXX@dial-ip-00000004;1' [Sep 11 10:41:38] DEBUG[15686][C-00000004]: app_dial.c:3060 dial_exec_full: Exiting with DIALSTATUS=CANCEL. [Sep 11 10:41:38] DEBUG[15686][C-00000004]: pbx.c:6511 __ast_pbx_run: Spawn extension (out-patterns,8XXXXXXXXXX,1) exited non-zero on 'IAX2/192.168.1.119:34749-10193' == Spawn extension (out-patterns, 8XXXXXXXXXX, 1) exited non-zero on 'IAX2/192.168.1.119:34749-10193' [Sep 11 10:41:38] DEBUG[15686][C-00000004]: channel.c:2533 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'IAX2/192.168.1.119:34749-10193' [Sep 11 10:41:38] DEBUG[15686][C-00000004]: channel.c:2533 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'IAX2/192.168.1.119:34749-10193' [Sep 11 10:41:38] DEBUG[15317]: devicestate.c:368 _ast_device_state: No provider found, checking channel drivers for Local - 8XXXXXXXXXX@dial-ip [Sep 11 10:41:38] DEBUG[15317]: devicestate.c:473 do_state_change: Changing state for Local/8XXXXXXXXXX@dial-ip - state 1 (Not in use) [Sep 11 10:41:38] ERROR[15320]: cdr.c:3082 ast_cdr_getvar: Unable to find CDR for channel IAX2/192.168.1.119:34749-10193 [Sep 11 10:41:38] DEBUG[15364]: app_queue.c:2374 device_state_cb: Device 'Local/8XXXXXXXXXX@dial-ip' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 11 10:41:38] DEBUG[15686][C-00000004]: pbx.c:4818 pbx_substitute_variables_helper_full: Function CDR(dst) result is '' [Sep 11 10:41:38] DEBUG[15686][C-00000004]: pbx.c:4818 pbx_substitute_variables_helper_full: Function CALLERID(all) result is '"221" <221>'