Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 parsing [default->1528] continue=false Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Regex (PASS) [1528] destination_number(1528) =~ /1528/ break=on-false Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(effective_destination_number=1528) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(bridge_pre_execute_bleg_app=sched_hangup) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(type=0) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(callstart=2023-03-22 09:34:38) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(hangup_after_bridge=true) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(continue_on_fail=TRUE) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(account_id=10) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(parent_id=0) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(entity_id=0) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(call_processed=internal) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(call_direction=local) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(accountname=default) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(filename1=astpp.pbx.lua) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(origination_rates=ID:0|CODE:^1528.*|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|CT:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:1|MARKUP:0|CI:0|ACCID:10) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action hash(insert/${domain_name}-call_return/${destination_number}/${caller_id_number}) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action limit(hash inbound 1528 1 !USER_BUSY) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action export(presence_data=x|||invoice check(3519495765)|||^1528.* // Local // 0||||||LOCAL|||49.14.135.162) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(calltype=LOCAL) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(daily_call_credit_limit=0.00000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(monthly_call_credit_limit=0.00000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(daily_call_used_limit=0.00000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(monthly_call_used_limit=0.00000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(receiver_accid=10) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action ring_ready() Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action sleep(5000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action db(insert/pickup_any_group_${callgroup}/1528/${uuid}) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(dialed_extension=transfer_call) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action export(dialed_extension=transfer_call) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action bind_meta_app(3 b s execute_extension::transfer_call_3 XML default) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action bind_meta_app(2 b s execute_extension::transfer_call_2 XML default) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action bind_meta_app(8 b s execute_extension::transfer_call_8 XML default) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(hangup_after_bridge=true) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action bridge({sip_invite_params=user=LOCAL,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=10}[leg_timeout=60 ] user/1528@astppqa602.astppbilling.org) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action ring_ready() Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action sleep(5000) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(on_busy_flag=1) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(on_busy_destination=) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(no_answer_flag=1) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(no_answer_destination=) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(not_register_flag=1) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(not_register_destination=) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(opensips_flag=1) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(variable_sip_to_host=astppqa602.astppbilling.org) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(opensips_domain=127.0.0.1) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(leg_timeout=60) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(userinfo_id=10) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(sip_destination_number=1528) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(did_number=1528) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action set(user_domain=astppqa602.astppbilling.org) Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action lua(astpp/lib/pbx/astpp-sipdevice-routing.lua) |--- Dialplan: Processing recursive conditions level:1 [1528_recur_1] require-nested=TRUE |--- Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Regex (PASS) [1528_recur_1] ${cond(${user_data 1528@${domain_name} param vm-enabled} == true ? YES : NO)}(YES) =~ /^YES$/ break=on-false |--- Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action answer() |--- Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action export(voicemail_alternate_greet_id=1528) |--- Dialplan: sofia/default/3519495765@astppqa602.astppbilling.org:5076 Action voicemail(default $${domain_name} 1528) 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_core_state_machine.c:281 (sofia/default/3519495765@astppqa602.astppbilling.org:5076) State Change CS_ROUTING -> CS_EXECUTE 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/3519495765@astppqa602.astppbilling.org:5076) State ROUTING going to sleep 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3519495765@astppqa602.astppbilling.org:5076) Running State Change CS_EXECUTE (Cur 1 Tot 50) 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/3519495765@astppqa602.astppbilling.org:5076) State EXECUTE 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_sofia.c:213 sofia/default/3519495765@astppqa602.astppbilling.org:5076 SOFIA EXECUTE 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_core_state_machine.c:323 sofia/default/3519495765@astppqa602.astppbilling.org:5076 Standard EXECUTE EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(effective_destination_number=1528) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [effective_destination_number]=[1528] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(bridge_pre_execute_bleg_app=sched_hangup) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [bridge_pre_execute_bleg_app]=[sched_hangup] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(bridge_pre_execute_bleg_data=+6000 normal_clearing) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(type=0) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [type]=[0] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(callstart=2023-03-22 09:34:38) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [callstart]=[2023-03-22 09:34:38] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(hangup_after_bridge=true) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [hangup_after_bridge]=[true] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(continue_on_fail=TRUE) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [continue_on_fail]=[TRUE] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(account_id=10) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [account_id]=[10] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(parent_id=0) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [parent_id]=[0] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(entity_id=0) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [entity_id]=[0] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(call_processed=internal) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [call_processed]=[internal] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(call_direction=local) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [call_direction]=[local] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(accountname=default) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [accountname]=[default] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(filename1=astpp.pbx.lua) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [filename1]=[astpp.pbx.lua] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(origination_rates=ID:0|CODE:^1528.*|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|CT:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:1|MARKUP:0|CI:0|ACCID:10) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [origination_rates]=[ID:0|CODE:^1528.*|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|CT:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:1|MARKUP:0|CI:0|ACCID:10] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 hash(insert/astppqa602.astppbilling.org-last_dial/3519495765/1528) EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 hash(insert/astppqa602.astppbilling.org-call_return/1528/3519495765) EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 limit(hash inbound 1528 1 !USER_BUSY) 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_limit.c:124 incr called: inbound_1528 max:1, interval:0 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_hash.c:193 Usage for inbound_1528 is now 1/1 EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 export(presence_data=x|||invoice check(3519495765)|||^1528.* // Local // 0||||||LOCAL|||49.14.135.162) 2023-03-22 09:34:38.883393 96.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||invoice check(3519495765)|||^1528.* // Local // 0||||||LOCAL|||49.14.135.162] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(calltype=LOCAL) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [calltype]=[LOCAL] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(daily_call_credit_limit=0.00000) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [daily_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(monthly_call_credit_limit=0.00000) 2023-03-22 09:34:38.883393 96.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/3519495765@astppqa602.astppbilling.org:5076 [monthly_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/3519495765@astppqa602.astppbilling.org:5076 set(daily_call_used_limit=0.00000)