-- Executing [7XXXXXXXXXX@dial-ip:1] Dial("Local/7XXXXXXXXXX@dial-ip-0000009a;2", "PJSIP/7XXXXXXXXXX@mango2,,T") in new stack
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: rtp_engine.c:1158 ast_rtp_instance_early_bridge_make_compatible: Can't find native functions for channel 'Local/8XXXXXXXXXX@dial-ip-0000009a;2'
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_name from Local/8XXXXXXXXXX@dial-ip-0000009a;2 to PJSIP/mango2-00000021.
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_num from Local/8XXXXXXXXXX@dial-ip-0000009a;2 to PJSIP/mango2-00000021.
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: channel.c:6458 ast_channel_inherit_variables: Inheriting variable __init_uid from Local/8XXXXXXXXXX@dial-ip-0000009a;2 to PJSIP/mango2-00000021.
[Sep 11 10:00:55] DEBUG[9065]: rtp_engine.c:425 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0xa8b8e54'
[Sep 11 10:00:55] DEBUG[9065]: res_rtp_asterisk.c:2489 ast_rtp_new: Allocated port 6358 for RTP instance '0xa8b8e54'
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 ICE session created, comp_cnt=2, role is Unknown agent
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 0 added: comp_id=1, type=host, foundation=Hb213f2a9, addr=XXX.XX.XXX.XXX:6358, base=XXX.XX.XXX.XXX:6358, prio=0x7effffff (2130706431)
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 1 added: comp_id=1, type=host, foundation=Hc0a800fe, addr=192.168.0.254:6358, base=192.168.0.254:6358, prio=0x7effffff (2130706431)
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 2 added: comp_id=1, type=host, foundation=Hac100001, addr=172.16.0.1:6358, base=172.16.0.1:6358, prio=0x7effffff (2130706431)
[Sep 11 10:00:55] DEBUG[9065]: rtp_engine.c:434 ast_rtp_instance_new: RTP instance '0xa8b8e54' is setup and ready to go
[Sep 11 10:00:55] DEBUG[9065]: res_rtp_asterisk.c:4721 ast_rtp_prop_set: Setup RTCP on RTP instance '0xa8b8e54'
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 3 added: comp_id=2, type=host, foundation=Hb213f2a9, addr=XXX.XX.XXX.XXX:6359, base=XXX.XX.XXX.XXX:6359, prio=0x7efffffe (2130706430)
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 4 added: comp_id=2, type=host, foundation=Hc0a800fe, addr=192.168.0.254:6359, base=192.168.0.254:6359, prio=0x7efffffe (2130706430)
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Candidate 5 added: comp_id=2, type=host, foundation=Hac100001, addr=172.16.0.1:6359, base=172.16.0.1:6359, prio=0x7efffffe (2130706430)
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:     icess0xa8be624 Destroying ICE session 0xa8be624
[Sep 11 10:00:55] DEBUG[9065]: pjsip:0 <?>:      ice_session.c ICE session 0xa8be624 destroyed
    -- Called PJSIP/7XXXXXXXXXX@mango2
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: channel.c:5490 set_format: Channel PJSIP/mango2-00000021 setting read format path: gsm -> gsm
[Sep 11 10:00:55] DEBUG[15128][C-00000016]: channel.c:5490 set_format: Channel PJSIP/mango2-00000021 setting write format path: gsm -> gsm
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2333 session_inv_on_state_changed: Source of transaction state change is TX_MSG
[Sep 11 10:00:55] DEBUG[15126][C-00000016]: chan_iax2.c:5759 iax2_indicate: Callno 5152: Config blocked sending control frame 22.
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2263 handle_outgoing: Sending request
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2263 handle_outgoing: Sending request
[Sep 11 10:00:55] DEBUG[9065]: res_pjsip_session.c:2236 handle_outgoing_request: Method is INVITE
...
[Sep 11 10:01:11] DEBUG[9065]: res_pjsip.c:3075 endpt_send_request: 0xa803c24: Wrapper created
[Sep 11 10:01:11] DEBUG[9065]: res_pjsip.c:3106 endpt_send_request: 0xa803c24: Set timer to 30000 msec
...
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: channel.c:2682 ast_hangup: Hanging up channel 'Local/8XXXXXXXXXX@dial-ip-0000009a;1'
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: app_dial.c:3060 dial_exec_full: Exiting with DIALSTATUS=CANCEL.
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: pbx.c:6511 __ast_pbx_run: Spawn extension (out-patterns,8XXXXXXXXXX,1) exited non-zero on 'IAX2/221-5152'
  == Spawn extension (out-patterns, 8XXXXXXXXXX, 1) exited non-zero on 'IAX2/221-5152'
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: channel.c:2533 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'IAX2/221-5152'
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: channel.c:2533 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'IAX2/221-5152'
[Sep 11 10:01:35] DEBUG[8328]: threadpool.c:508 grow: Increasing threadpool stasis-core's size by 1
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: channel.c:2682 ast_hangup: Hanging up channel 'PJSIP/mango2-00000021'
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: chan_pjsip.c:1702 hangup_cause2sip: AST hangup cause 0 (no match found in PJSIP)
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: app_dial.c:3060 dial_exec_full: Exiting with DIALSTATUS=CANCEL.
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: pbx.c:6511 __ast_pbx_run: Spawn extension (dial-ip,7XXXXXXXXXX,1) exited non-zero on 'Local/8XXXXXXXXXX@dial-ip-0000009a;2'
  == Spawn extension (dial-ip, 7XXXXXXXXXX, 1) exited non-zero on 'Local/8XXXXXXXXXX@dial-ip-0000009a;2'
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: channel.c:2533 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'Local/8XXXXXXXXXX@dial-ip-0000009a;2'
[Sep 11 10:01:35] DEBUG[15128][C-00000016]: channel.c:2682 ast_hangup: Hanging up channel 'Local/8XXXXXXXXXX@dial-ip-0000009a;2'
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:368 _ast_device_state: No provider found, checking channel drivers for Local - 8XXXXXXXXXX@dial-ip
[Sep 11 10:01:35] DEBUG[8340]: core_local.c:315 local_devicestate: Checking if extension 8XXXXXXXXXX@dial-ip exists (devicestate)
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:473 do_state_change: Changing state for Local/8XXXXXXXXXX@dial-ip - state 1 (Not in use)
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:368 _ast_device_state: No provider found, checking channel drivers for Local - 8XXXXXXXXXX@dial-ip
[Sep 11 10:01:35] DEBUG[8340]: core_local.c:315 local_devicestate: Checking if extension 8XXXXXXXXXX@dial-ip exists (devicestate)
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:473 do_state_change: Changing state for Local/8XXXXXXXXXX@dial-ip - state 1 (Not in use)
[Sep 11 10:01:35] DEBUG[8384]: 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:01:35] DEBUG[8384]: 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:01:35] DEBUG[9065]: pjsip:0 <?>:       inv0xa8aba74 .Delaying CANCEL since no provisional response is received yet
[Sep 11 10:01:35] DEBUG[8328]: threadpool.c:508 grow: Increasing threadpool stasis-core's size by 1
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:368 _ast_device_state: No provider found, checking channel drivers for PJSIP - mango2
[Sep 11 10:01:35] DEBUG[8340]: devicestate.c:473 do_state_change: Changing state for PJSIP/mango2 - state 1 (Not in use)
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: pbx.c:4818 pbx_substitute_variables_helper_full: Function CDR(dst) result is ''
[Sep 11 10:01:35] DEBUG[15126][C-00000016]: pbx.c:4818 pbx_substitute_variables_helper_full: Function CALLERID(all) result is '"221" <221>'