[2022-05-26 18:31:22] Asterisk 16.23.0 built by root @ asteriskcomb on a x86_64 running Linux on 2022-01-13 17:45:30 UTC [2022-05-26 18:31:22] VERBOSE[5487] logger.c: Asterisk Queue Logger restarted [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] bridge_channel.c: Channel PJSIP/1508-00002ba6 left 'simple_bridge' basic-bridge <27a1730a-621d-49a3-8865-35c2749b4b25> [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Spawn extension (sub_call_pstn, start, 16) exited non-zero on 'PJSIP/1508-00002ba6' [2022-05-26 18:31:23] VERBOSE[5464][C-00001022] bridge_channel.c: Channel SIP/colegi-metges-0000083f left 'simple_bridge' basic-bridge <27a1730a-621d-49a3-8865-35c2749b4b25> [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [h@sub_call_pstn:1] NoOp("PJSIP/1508-00002ba6", "Colgamos llamada 606256088") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [h@sub_call_pstn:2] Gosub("PJSIP/1508-00002ba6", "sub_notify_API,start,1(sub_call_pstn,OUTGOING,OUT)") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/1508-00002ba6", "ARG1 CONTEXT:sub_call_pstn | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:OUTGOING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: OUT") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/1508-00002ba6", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [h@sub_call_pstn:3] Gosub("PJSIP/1508-00002ba6", "sub_alert_message_pstn,start,1())") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:1] NoOp("PJSIP/1508-00002ba6", "935678888 |ARG1: )| CONTEXT: sub_alert_message_pstn | ") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:2] ExecIf("PJSIP/1508-00002ba6", "0?Return") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:3] ExecIf("PJSIP/1508-00002ba6", "0??Congestion(2)") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:4] ExecIf("PJSIP/1508-00002ba6", "0?Busy(2)") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:5] ExecIf("PJSIP/1508-00002ba6", "0?Congestion(2)") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:6] ExecIf("PJSIP/1508-00002ba6", "0?Congestion(2)") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [start@sub_alert_message_pstn:7] Return("PJSIP/1508-00002ba6", "") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Executing [h@sub_call_pstn:4] Hangup("PJSIP/1508-00002ba6", "") in new stack [2022-05-26 18:31:23] VERBOSE[5281][C-00001022] pbx.c: Spawn extension (sub_call_pstn, h, 4) exited non-zero on 'PJSIP/1508-00002ba6' [2022-05-26 18:31:23] VERBOSE[5497] manager.c: HTTP Manager 'zabbix' logged on from 172.30.1.10 [2022-05-26 18:31:23] VERBOSE[921] asterisk.c: Remote UNIX connection [2022-05-26 18:31:23] VERBOSE[5502] asterisk.c: Remote UNIX connection disconnected [2022-05-26 18:31:23] VERBOSE[5498] manager.c: HTTP Manager 'zabbix' logged off from 172.30.1.10 [2022-05-26 18:31:24] VERBOSE[5504] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:31:25] VERBOSE[5505] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:31:25] VERBOSE[921] asterisk.c: Remote UNIX connection [2022-05-26 18:31:25] VERBOSE[5511] asterisk.c: Remote UNIX connection disconnected [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [0606256088@from-users:1] Gosub("PJSIP/1508-00002baa", "sub_pstn_task,start,1(606256088,movil)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:1] NoOp("PJSIP/1508-00002baa", "movil,606256088,") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:2] Gosub("PJSIP/1508-00002baa", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/1508-00002baa", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/1508-00002baa", "disabled") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:3] Gosub("PJSIP/1508-00002baa", "sub_determine_scenario,start,1(outgoing,movil,606256088)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:1] NoOp("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:2] SIPAddHeader("PJSIP/1508-00002baa", ""X-CID:"") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:3] GotoIf("PJSIP/1508-00002baa", "0?pbxcfu") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:4] GotoIf("PJSIP/1508-00002baa", "0?sipbxfer") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:5] GotoIf("PJSIP/1508-00002baa", "0?pbxatxfer") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:6] GotoIf("PJSIP/1508-00002baa", "0?pbxbxfer") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:7] GotoIf("PJSIP/1508-00002baa", "0?sipcfu") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:8] GotoIf("PJSIP/1508-00002baa", "0?disacall") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:9] Set("PJSIP/1508-00002baa", "__SRCEXTEN=1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:10] ExecIf("PJSIP/1508-00002baa", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:11] ExecIf("PJSIP/1508-00002baa", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:12] ExecIf("PJSIP/1508-00002baa", "0?Set(__SRCEXTEN=2990)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:13] Goto("PJSIP/1508-00002baa", "normal") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx_builtins.c: Goto (sub_determine_scenario,start,46) [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:46] ExecIf("PJSIP/1508-00002baa", "0?Set(CALLERID(num)=1508)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:47] ExecIf("PJSIP/1508-00002baa", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:48] Goto("PJSIP/1508-00002baa", "cdr") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx_builtins.c: Goto (sub_determine_scenario,start,49) [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:49] NoOp("PJSIP/1508-00002baa", "1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:50] Set("PJSIP/1508-00002baa", "ARRAY(id,seatId,departamentId)=29149,12,102") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:51] Set("PJSIP/1508-00002baa", "CDR(seatId)=12") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:52] Set("PJSIP/1508-00002baa", "CDR(departamentId)=102") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:53] Set("PJSIP/1508-00002baa", "EXTEN_Id=29149") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:54] Set("PJSIP/1508-00002baa", "CDR(type)=outgoing") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:55] Set("PJSIP/1508-00002baa", "CDR(subtype)=movil") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:56] Set("PJSIP/1508-00002baa", "CDR(src_extension)=1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:57] Set("PJSIP/1508-00002baa", "CDR(src_callerid)=1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:58] Set("PJSIP/1508-00002baa", "CDR(dst_dialed)=606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_determine_scenario:59] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] func_odbc.c: Found no rows [SELECT country,detail,blackList from prefijos_internacional where '6256088' like concat(prefijo,'%') order by prefijo desc limit 1] [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:4] ExecIf("PJSIP/1508-00002baa", "0?Set(ARRAY(geo_country,geo_zone,geo_blackList)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:5] GotoIf("PJSIP/1508-00002baa", "0?blacklist") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:6] NoOp("PJSIP/1508-00002baa", "movil,606256088,1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:7] Set("PJSIP/1508-00002baa", "__ddi_number=606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:8] Gosub("PJSIP/1508-00002baa", "define_ddi_envioroment,s,1") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:1] NoOp("PJSIP/1508-00002baa", "Recogiendo variables para el numero 606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] func_odbc.c: Found no rows [SELECT id,seatId,departamentId,timeOut,custom,description FROM ddi WHERE ddi = '606256088'] [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:2] Set("PJSIP/1508-00002baa", "ARRAY(ddi_Id,seatId,departamentId,ddi_timeOut,ddi_custom,ddi_description)=") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:3] Set("PJSIP/1508-00002baa", "ARRAY(CDR(seatId),CDR(departamentId)=,)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:4] AGI("PJSIP/1508-00002baa", "processing.php,") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/processing.php [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] res_agi.c: processing.php,: ID de Timegroup a usar [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] res_agi.c: AGI Script processing.php completed, returning 0 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:5] Gosub("PJSIP/1508-00002baa", "sub_notify_API,start,1(define_ddi_envioroment,INCOMING,IN)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/1508-00002baa", "ARG1 CONTEXT:define_ddi_envioroment | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:INCOMING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: IN") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/1508-00002baa", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:6] NoOp("PJSIP/1508-00002baa", "TimeGroupID ddi_timeGroupId: | ddi_description: ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [s@define_ddi_envioroment:7] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:9] ExecIf("PJSIP/1508-00002baa", "0?Set(noPrefix=1)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:10] GotoIf("PJSIP/1508-00002baa", "0?from-pstn,606256088,1") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:11] NoOp("PJSIP/1508-00002baa", "Llamada a:606256088 desde:1508 arg2 movil DISACALL: NUMCORTA ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:12] Set("PJSIP/1508-00002baa", "ARRAY(category_id,category_OutboundRoutesId,PREFIX_OUTGOING,category_FailOverOutboundRoutesId)=121,13,,7") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] func_odbc.c: Found no rows [SELECT d.id,d.outboundRoutesId,d.callerId,d.failOverOutboundRoutesId FROM categories d LEFT JOIN user u ON (d.categoryGroupId=u.categoryGroupId) WHERE u.extension='' AND '606256088' like concat(prefix,'%') order by prefix desc] [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:13] ExecIf("PJSIP/1508-00002baa", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:14] ExecIf("PJSIP/1508-00002baa", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=121,13,,7)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:15] NoOp("PJSIP/1508-00002baa", " category_id 121 ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:16] Set("PJSIP/1508-00002baa", "_PREFIX_OUTGOING=") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:17] Set("PJSIP/1508-00002baa", "__TRANSFER_CONTEXT=from-users") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:18] GotoIf("PJSIP/1508-00002baa", "1?conpermiso:sinpermiso") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx_builtins.c: Goto (sub_pstn_task,start,19) [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:19] Set("PJSIP/1508-00002baa", "CALLERID(num)=935678888") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:20] Set("PJSIP/1508-00002baa", "__USERDDI=935678888") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:21] Set("PJSIP/1508-00002baa", "CHANNEL(accountcode)=1508") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:22] ExecIf("PJSIP/1508-00002baa", "0?SIPAddHeader(Privacy:id)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:23] Gosub("PJSIP/1508-00002baa", "sub_record_call,start,1(1508,outgoing,606256088)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:1] NoOp("PJSIP/1508-00002baa", "sub_record_call: arg1: 1508 arg2: outgoing arg3: 606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:2] GotoIf("PJSIP/1508-00002baa", "0?volver") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:3] Set("PJSIP/1508-00002baa", "RECORDPREF=000") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:4] Verbose("PJSIP/1508-00002baa", "RECORDPREF 000 inc :0 out: 0 int: 0 ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] app_verbose.c: RECORDPREF 000 inc :0 out: 0 int: 0 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:5] GotoIf("PJSIP/1508-00002baa", "0?inc") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:6] GotoIf("PJSIP/1508-00002baa", "0?int") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:7] GotoIf("PJSIP/1508-00002baa", "1?out") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx_builtins.c: Goto (sub_record_call,start,12) [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:12] GotoIf("PJSIP/1508-00002baa", "0?graba") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_record_call:13] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_pstn_task:24] Gosub("PJSIP/1508-00002baa", "sub_call_pstn,start,1(606256088,13,7)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:1] NoOp("PJSIP/1508-00002baa", "subrutina de llamar a pstn ARG1:Destino 606256088 | ARG2: es ID destino 1 13 | ARG3: es ID destino 2 7 ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:2] Gosub("PJSIP/1508-00002baa", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/1508-00002baa", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/1508-00002baa", "disabled") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:3] Gosub("PJSIP/1508-00002baa", "sub_geolocation_callee,start,1(606256088)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_geolocation_callee:1] Verbose("PJSIP/1508-00002baa", "tratando de geolocalizar 606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] app_verbose.c: tratando de geolocalizar 606256088 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_geolocation_callee:2] GotoIf("PJSIP/1508-00002baa", "0?int") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_geolocation_callee:3] Set("PJSIP/1508-00002baa", "CDR(dst_geocountry)=España") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_geolocation_callee:4] ExecIf("PJSIP/1508-00002baa", "1?Return()") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:4] Set("PJSIP/1508-00002baa", "__DESTINATION=606256088") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:5] ExecIf("PJSIP/1508-00002baa", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:6] ExecIf("PJSIP/1508-00002baa", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:7] Set("PJSIP/1508-00002baa", "CALLERID(all)=935678888") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:8] Set("PJSIP/1508-00002baa", "CDR(src_callerid)=") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:9] ExecIf("PJSIP/1508-00002baa", "1?SIPAddHeader("X-Exten:1508")") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:10] Set("PJSIP/1508-00002baa", "ARRAY(PEER,CALLERID_OPTION)=colegi-metges,,") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:11] NoOp("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:12] Gosub("PJSIP/1508-00002baa", "custom_sub_callerid,start,1(606256088)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@custom_sub_callerid:1] NoOp("PJSIP/1508-00002baa", "custom_sub_callerid llamada a:606256088 PEER:colegi-metges PREFIX_OUTGOING:") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@custom_sub_callerid:2] NoOp("PJSIP/1508-00002baa", "PREFIX ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@custom_sub_callerid:3] ExecIf("PJSIP/1508-00002baa", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@custom_sub_callerid:4] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:13] SIPRemoveHeader("PJSIP/1508-00002baa", "Alert-info:") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:14] NoOp("PJSIP/1508-00002baa", " DESTINATION 606256088 | PEER colegi-metges ") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:15] Set("PJSIP/1508-00002baa", "ARRAY(SIP_MODE)=PJSIP") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_call_pstn:16] Dial("PJSIP/1508-00002baa", "SIP/colegi-metges/606256088,300,T") in new stack [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] netsock2.c: Using SIP RTP CoS mark 5 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] app_dial.c: Called SIP/colegi-metges/606256088 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] app_dial.c: SIP/colegi-metges-00000841 is ringing [2022-05-26 18:31:26] VERBOSE[1085][C-00001025] res_rtp_asterisk.c: 0x14b1c008e090 -- Strict RTP learning after remote address set to: 212.36.72.3:41264 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] app_dial.c: SIP/colegi-metges-00000841 is making progress passing it to PJSIP/1508-00002baa [2022-05-26 18:31:26] VERBOSE[27230] res_rtp_asterisk.c: 0x14b210f311a0 -- Strict RTP learning after remote address set to: 172.25.150.78:46210 [2022-05-26 18:31:26] VERBOSE[5513][C-00001025] res_rtp_asterisk.c: 0x14b210f311a0 -- Strict RTP switching to RTP target address 172.25.150.78:46210 as source [2022-05-26 18:31:27] VERBOSE[5513][C-00001025] app_dial.c: SIP/colegi-metges-00000841 is making progress passing it to PJSIP/1508-00002baa [2022-05-26 18:31:27] VERBOSE[5513][C-00001025] res_rtp_asterisk.c: 0x14b1c008e090 -- Strict RTP switching to RTP target address 212.36.72.3:41264 as source [2022-05-26 18:31:27] VERBOSE[5513][C-00001025] app_dial.c: SIP/colegi-metges-00000841 is making progress passing it to PJSIP/1508-00002baa [2022-05-26 18:31:27] VERBOSE[921] asterisk.c: Remote UNIX connection [2022-05-26 18:31:27] VERBOSE[5519] asterisk.c: Remote UNIX connection disconnected [2022-05-26 18:31:29] VERBOSE[921] asterisk.c: Remote UNIX connection [2022-05-26 18:31:29] VERBOSE[5525] asterisk.c: Remote UNIX connection disconnected [2022-05-26 18:31:31] VERBOSE[5513][C-00001025] res_rtp_asterisk.c: 0x14b1c008e090 -- Strict RTP learning complete - Locking on source address 212.36.72.3:41264 [2022-05-26 18:31:31] VERBOSE[5513][C-00001025] res_rtp_asterisk.c: 0x14b210f311a0 -- Strict RTP learning complete - Locking on source address 172.25.150.78:46210 [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] bridge_channel.c: Channel SIP/colegi-metges-0000083e left 'simple_bridge' basic-bridge <3a18935e-a50b-45a4-83a0-779f1d64db30> [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] bridge_channel.c: Channel PJSIP/1513-00002ba5 left 'simple_bridge' basic-bridge <3a18935e-a50b-45a4-83a0-779f1d64db30> [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] app_stack.c: PJSIP/1513-00002ba5 Internal Gosub(outbound_handler,outbound_handler,1(PJSIP/1518-00002ba4)) start [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:1] NoOp("PJSIP/1513-00002ba5", "Destination channel PJSIP/1518-00002ba4 has hungup") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:2] Set("PJSIP/1513-00002ba5", "HANGUPCAUSE_STRING=PJSIP/1513-00002ba5,SIP/colegi-metges-0000083e") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:3] ExecIf("PJSIP/1513-00002ba5", "0?Return()") in new stack [2022-05-26 18:31:32] WARNING[5145][C-00001021] func_hangupcause.c: Information type not recognized (SIP/colegi-metges-0000083e,tech) [2022-05-26 18:31:32] WARNING[5145][C-00001021] func_hangupcause.c: Information type not recognized (SIP/colegi-metges-0000083e,ast) [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:4] NoOp("PJSIP/1513-00002ba5", "Got Channel ID PJSIP/1513-00002ba5,SIP/colegi-metges-0000083e master PJSIP/1518-00002ba4 with Technology Cause Code , Asterisk Cause Code ") in new stack [2022-05-26 18:31:32] WARNING[5145][C-00001021] func_hangupcause.c: Information type not recognized (SIP/colegi-metges-0000083e,tech) [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:5] Set("PJSIP/1513-00002ba5", "SHARED(SIPcause,PJSIP/1518-00002ba4)=") in new stack [2022-05-26 18:31:32] ERROR[5145][C-00001021] func_global.c: Channel 'PJSIP/1518-00002ba4' not found! Variable 'SIPcause' not set to ''. [2022-05-26 18:31:32] ERROR[5145][C-00001021] func_global.c: Channel 'PJSIP/1518-00002ba4' not found! Variable 'SIPcause' will be blank. [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:6] Set("PJSIP/1513-00002ba5", "SIPMESAGE=") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Spawn extension (sub_call_queue, start, 29) exited non-zero on 'SIP/colegi-metges-0000083e' [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:1] NoOp("SIP/colegi-metges-0000083e", "h sub_call_queue") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:7] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=ANSWER)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:2] Gosub("SIP/colegi-metges-0000083e", "custom-sub_QUEUEBLFCOUNT,start,1(8815,Mediconsulting,resta)") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:8] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=FORBIDDEN)") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:9] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=DND)") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:10] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=BUSY)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:1] NoOp("SIP/colegi-metges-0000083e", "custom-sub_QUEUEBLFCOUNT llamada a cola num:8815 nombre:Mediconsulting solicita: resta ") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:11] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=NOANSWER)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:2] ExecIf("SIP/colegi-metges-0000083e", "0?Goto(suma):Goto(resta)") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:12] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=DND)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx_builtins.c: Goto (custom-sub_QUEUEBLFCOUNT,start,11) [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:13] ExecIf("PJSIP/1513-00002ba5", "0?Set(SHARED(SIPCAUSE,PJSIP/1518-00002ba4)=CONGESTION)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:11] NoOp("SIP/colegi-metges-0000083e", "Resta llamadada a cola num:8815 name:Mediconsulting DIALSTATUS: ") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] pbx.c: Executing [outbound_handler@outbound_handler:14] Return("PJSIP/1513-00002ba5", "") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:12] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:colastate_8815)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] app_stack.c: Spawn extension (from-users, , 1) exited non-zero on 'PJSIP/1513-00002ba5' [2022-05-26 18:31:32] VERBOSE[5145][C-00001021] app_stack.c: PJSIP/1513-00002ba5 Internal Gosub(outbound_handler,outbound_handler,1(PJSIP/1518-00002ba4)) complete GOSUB_RETVAL= [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:13] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:colastate_8815)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:14] Set("SIP/colegi-metges-0000083e", "count=0") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:15] Goto("SIP/colegi-metges-0000083e", "blf_0") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx_builtins.c: Goto (custom-sub_QUEUEBLFCOUNT,start,16) [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:16] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_0))=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:17] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_1)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:18] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_2)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:19] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_3)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:20] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_4)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:21] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_5)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:22] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_6)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:23] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_7)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:24] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:cola_8815_8)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@custom-sub_QUEUEBLFCOUNT:25] Return("SIP/colegi-metges-0000083e", "") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:3] ExecIf("SIP/colegi-metges-0000083e", "0?Set(CDR(record_file)=2022/05/26/)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:4] ExecIf("SIP/colegi-metges-0000083e", "1?Set(Q_REASON=Q_OUT):SET(Q_REASON=Q_NO_AGENT") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:5] Gosub("SIP/colegi-metges-0000083e", "sub_notify_API,start,1(sub_call_queue,QUEUE,Q_OUT)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@sub_notify_API:1] NoOp("SIP/colegi-metges-0000083e", "ARG1 CONTEXT:sub_call_queue | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:QUEUE| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: Q_OUT") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@sub_notify_API:2] ExecIf("SIP/colegi-metges-0000083e", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:6] Set("SIP/colegi-metges-0000083e", "DEVICE_STATE(Custom:agent_1518)=NOT_INUSE") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [h@sub_call_queue:7] Gosub("SIP/colegi-metges-0000083e", "sub_call_hangup,start,1(busy)") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@sub_call_hangup:1] NoOp("SIP/colegi-metges-0000083e", " start | sub_call_hangup ") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@sub_call_hangup:2] ExecIf("SIP/colegi-metges-0000083e", "0?Gosub(sub_update_call_status,start,1(,,1518))") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [start@sub_call_hangup:3] Goto("SIP/colegi-metges-0000083e", "busy,1") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx_builtins.c: Goto (sub_call_hangup,busy,1) [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:1] NoOp("SIP/colegi-metges-0000083e", "busy") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:2] NoOp("SIP/colegi-metges-0000083e", " sub_call_hangup | busy ") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:3] NoOp("SIP/colegi-metges-0000083e", "DIALSTATUS: A: 0934316669 > B:busy ") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:4] GotoIf("SIP/colegi-metges-0000083e", "0?requestcallback,1") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:5] GotoIf("SIP/colegi-metges-0000083e", "0?requestcallback,1") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [busy@sub_call_hangup:6] GotoIf("SIP/colegi-metges-0000083e", "0?requestcallback,1:nocallback,1") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx_builtins.c: Goto (sub_call_hangup,nocallback,1) [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [nocallback@sub_call_hangup:1] NoOp("SIP/colegi-metges-0000083e", "sub_call_hangup | nocallback") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Executing [nocallback@sub_call_hangup:2] Congestion("SIP/colegi-metges-0000083e", "2") in new stack [2022-05-26 18:31:32] VERBOSE[4964][C-00001020] pbx.c: Spawn extension (sub_call_hangup, nocallback, 2) exited non-zero on 'SIP/colegi-metges-0000083e' [2022-05-26 18:31:48] VERBOSE[5513][C-00001025] app_dial.c: SIP/colegi-metges-00000841 answered PJSIP/1508-00002baa [2022-05-26 18:31:48] VERBOSE[5567][C-00001025] bridge_channel.c: Channel SIP/colegi-metges-00000841 joined 'simple_bridge' basic-bridge [2022-05-26 18:31:48] VERBOSE[5513][C-00001025] bridge_channel.c: Channel PJSIP/1508-00002baa joined 'simple_bridge' basic-bridge [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] bridge_channel.c: Channel PJSIP/1508-00002baa left 'simple_bridge' basic-bridge [2022-05-26 18:31:52] VERBOSE[5567][C-00001025] bridge_channel.c: Channel SIP/colegi-metges-00000841 left 'simple_bridge' basic-bridge [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Spawn extension (sub_call_pstn, start, 16) exited non-zero on 'PJSIP/1508-00002baa' [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [h@sub_call_pstn:1] NoOp("PJSIP/1508-00002baa", "Colgamos llamada 606256088") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [h@sub_call_pstn:2] Gosub("PJSIP/1508-00002baa", "sub_notify_API,start,1(sub_call_pstn,OUTGOING,OUT)") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/1508-00002baa", "ARG1 CONTEXT:sub_call_pstn | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:OUTGOING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: OUT") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/1508-00002baa", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [h@sub_call_pstn:3] Gosub("PJSIP/1508-00002baa", "sub_alert_message_pstn,start,1())") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:1] NoOp("PJSIP/1508-00002baa", "935678888 |ARG1: )| CONTEXT: sub_alert_message_pstn | ") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:2] ExecIf("PJSIP/1508-00002baa", "0?Return") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:3] ExecIf("PJSIP/1508-00002baa", "0??Congestion(2)") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:4] ExecIf("PJSIP/1508-00002baa", "0?Busy(2)") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:5] ExecIf("PJSIP/1508-00002baa", "0?Congestion(2)") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:6] ExecIf("PJSIP/1508-00002baa", "0?Congestion(2)") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [start@sub_alert_message_pstn:7] Return("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Executing [h@sub_call_pstn:4] Hangup("PJSIP/1508-00002baa", "") in new stack [2022-05-26 18:31:52] VERBOSE[5513][C-00001025] pbx.c: Spawn extension (sub_call_pstn, h, 4) exited non-zero on 'PJSIP/1508-00002baa' [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] bridge_channel.c: Channel PJSIP/1122-00002ba8 left 'simple_bridge' basic-bridge <970d1dc1-c232-49d0-a68a-60a16f002543> [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] bridge_channel.c: Channel PJSIP/1190-00002ba9 left 'simple_bridge' basic-bridge <970d1dc1-c232-49d0-a68a-60a16f002543> [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Spawn extension (sub_call_user, 1190, 37) exited non-zero on 'PJSIP/1122-00002ba8' [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:1] NoOp("PJSIP/1122-00002ba8", "") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] app_stack.c: PJSIP/1190-00002ba9 Internal Gosub(outbound_handler,outbound_handler,1(PJSIP/1122-00002ba8)) start [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:1] NoOp("PJSIP/1190-00002ba9", "Destination channel PJSIP/1122-00002ba8 has hungup") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:2] Set("PJSIP/1190-00002ba9", "HANGUPCAUSE_STRING=PJSIP/1190-00002ba9") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:2] NoOp("PJSIP/1122-00002ba8", "DIALSTATUS: ANSWER A: 1122 > B: h | ATTENDEDTRANSFER ") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:3] ExecIf("PJSIP/1122-00002ba8", "0?Goto(h,transferencia_ok)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:3] ExecIf("PJSIP/1190-00002ba9", "0?Return()") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:4] GotoIf("PJSIP/1122-00002ba8", "0?requestcallback,1") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:4] NoOp("PJSIP/1190-00002ba9", "Got Channel ID PJSIP/1190-00002ba9 master PJSIP/1122-00002ba8 with Technology Cause Code SIP 200 OK, Asterisk Cause Code Normal Clearing") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:5] GotoIf("PJSIP/1122-00002ba8", "0?requestcallback,1") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:5] Set("PJSIP/1190-00002ba9", "SHARED(SIPcause,PJSIP/1122-00002ba8)=SIP 200 OK") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx.c: Executing [h@sub_call_user:6] GotoIf("PJSIP/1122-00002ba8", "0?requestcallback,1:nocallback,1") in new stack [2022-05-26 18:32:01] VERBOSE[5294][C-00001024] pbx_builtins.c: Goto (sub_call_user,nocallback,1) [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:6] Set("PJSIP/1190-00002ba9", "SIPMESAGE=SIP 200 OK") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:7] ExecIf("PJSIP/1190-00002ba9", "1?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=ANSWER)") in new stack [2022-05-26 18:32:01] ERROR[5405][C-00001024] func_global.c: Channel 'PJSIP/1122-00002ba8' not found! Variable 'SIPCAUSE' not set to 'ANSWER'. [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:8] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=FORBIDDEN)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:9] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=DND)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:10] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=BUSY)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:11] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=NOANSWER)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:12] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=DND)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:13] ExecIf("PJSIP/1190-00002ba9", "0?Set(SHARED(SIPCAUSE,PJSIP/1122-00002ba8)=CONGESTION)") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] pbx.c: Executing [outbound_handler@outbound_handler:14] Return("PJSIP/1190-00002ba9", "") in new stack [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] app_stack.c: Spawn extension (from-users, , 1) exited non-zero on 'PJSIP/1190-00002ba9' [2022-05-26 18:32:01] VERBOSE[5405][C-00001024] app_stack.c: PJSIP/1190-00002ba9 Internal Gosub(outbound_handler,outbound_handler,1(PJSIP/1122-00002ba8)) complete GOSUB_RETVAL= [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [0606256088@from-users:1] Gosub("PJSIP/1508-00002bab", "sub_pstn_task,start,1(606256088,movil)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:1] NoOp("PJSIP/1508-00002bab", "movil,606256088,") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:2] Gosub("PJSIP/1508-00002bab", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/1508-00002bab", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/1508-00002bab", "disabled") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:3] Gosub("PJSIP/1508-00002bab", "sub_determine_scenario,start,1(outgoing,movil,606256088)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:1] NoOp("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:2] SIPAddHeader("PJSIP/1508-00002bab", ""X-CID:"") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:3] GotoIf("PJSIP/1508-00002bab", "0?pbxcfu") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:4] GotoIf("PJSIP/1508-00002bab", "0?sipbxfer") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:5] GotoIf("PJSIP/1508-00002bab", "0?pbxatxfer") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:6] GotoIf("PJSIP/1508-00002bab", "0?pbxbxfer") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:7] GotoIf("PJSIP/1508-00002bab", "0?sipcfu") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:8] GotoIf("PJSIP/1508-00002bab", "0?disacall") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:9] Set("PJSIP/1508-00002bab", "__SRCEXTEN=1508") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:10] ExecIf("PJSIP/1508-00002bab", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:11] ExecIf("PJSIP/1508-00002bab", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:12] ExecIf("PJSIP/1508-00002bab", "0?Set(__SRCEXTEN=2990)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:13] Goto("PJSIP/1508-00002bab", "normal") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx_builtins.c: Goto (sub_determine_scenario,start,46) [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:46] ExecIf("PJSIP/1508-00002bab", "0?Set(CALLERID(num)=1508)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:47] ExecIf("PJSIP/1508-00002bab", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:48] Goto("PJSIP/1508-00002bab", "cdr") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx_builtins.c: Goto (sub_determine_scenario,start,49) [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:49] NoOp("PJSIP/1508-00002bab", "1508") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:50] Set("PJSIP/1508-00002bab", "ARRAY(id,seatId,departamentId)=29149,12,102") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:51] Set("PJSIP/1508-00002bab", "CDR(seatId)=12") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:52] Set("PJSIP/1508-00002bab", "CDR(departamentId)=102") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:53] Set("PJSIP/1508-00002bab", "EXTEN_Id=29149") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:54] Set("PJSIP/1508-00002bab", "CDR(type)=outgoing") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:55] Set("PJSIP/1508-00002bab", "CDR(subtype)=movil") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:56] Set("PJSIP/1508-00002bab", "CDR(src_extension)=1508") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:57] Set("PJSIP/1508-00002bab", "CDR(src_callerid)=1508") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:58] Set("PJSIP/1508-00002bab", "CDR(dst_dialed)=606256088") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_determine_scenario:59] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] func_odbc.c: Found no rows [SELECT country,detail,blackList from prefijos_internacional where '6256088' like concat(prefijo,'%') order by prefijo desc limit 1] [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:4] ExecIf("PJSIP/1508-00002bab", "0?Set(ARRAY(geo_country,geo_zone,geo_blackList)=)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:5] GotoIf("PJSIP/1508-00002bab", "0?blacklist") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:6] NoOp("PJSIP/1508-00002bab", "movil,606256088,1508") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:7] Set("PJSIP/1508-00002bab", "__ddi_number=606256088") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:8] Gosub("PJSIP/1508-00002bab", "define_ddi_envioroment,s,1") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:1] NoOp("PJSIP/1508-00002bab", "Recogiendo variables para el numero 606256088") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] func_odbc.c: Found no rows [SELECT id,seatId,departamentId,timeOut,custom,description FROM ddi WHERE ddi = '606256088'] [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:2] Set("PJSIP/1508-00002bab", "ARRAY(ddi_Id,seatId,departamentId,ddi_timeOut,ddi_custom,ddi_description)=") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:3] Set("PJSIP/1508-00002bab", "ARRAY(CDR(seatId),CDR(departamentId)=,)") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:4] AGI("PJSIP/1508-00002bab", "processing.php,") in new stack [2022-05-26 18:32:12] VERBOSE[5694][C-00001026] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/processing.php [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] res_agi.c: processing.php,: ID de Timegroup a usar [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] res_agi.c: AGI Script processing.php completed, returning 0 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:5] Gosub("PJSIP/1508-00002bab", "sub_notify_API,start,1(define_ddi_envioroment,INCOMING,IN)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/1508-00002bab", "ARG1 CONTEXT:define_ddi_envioroment | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:INCOMING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: IN") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/1508-00002bab", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:6] NoOp("PJSIP/1508-00002bab", "TimeGroupID ddi_timeGroupId: | ddi_description: ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [s@define_ddi_envioroment:7] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:9] ExecIf("PJSIP/1508-00002bab", "0?Set(noPrefix=1)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:10] GotoIf("PJSIP/1508-00002bab", "0?from-pstn,606256088,1") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:11] NoOp("PJSIP/1508-00002bab", "Llamada a:606256088 desde:1508 arg2 movil DISACALL: NUMCORTA ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:12] Set("PJSIP/1508-00002bab", "ARRAY(category_id,category_OutboundRoutesId,PREFIX_OUTGOING,category_FailOverOutboundRoutesId)=121,13,,7") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] func_odbc.c: Found no rows [SELECT d.id,d.outboundRoutesId,d.callerId,d.failOverOutboundRoutesId FROM categories d LEFT JOIN user u ON (d.categoryGroupId=u.categoryGroupId) WHERE u.extension='' AND '606256088' like concat(prefix,'%') order by prefix desc] [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:13] ExecIf("PJSIP/1508-00002bab", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:14] ExecIf("PJSIP/1508-00002bab", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=121,13,,7)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:15] NoOp("PJSIP/1508-00002bab", " category_id 121 ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:16] Set("PJSIP/1508-00002bab", "_PREFIX_OUTGOING=") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:17] Set("PJSIP/1508-00002bab", "__TRANSFER_CONTEXT=from-users") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:18] GotoIf("PJSIP/1508-00002bab", "1?conpermiso:sinpermiso") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx_builtins.c: Goto (sub_pstn_task,start,19) [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:19] Set("PJSIP/1508-00002bab", "CALLERID(num)=935678888") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:20] Set("PJSIP/1508-00002bab", "__USERDDI=935678888") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:21] Set("PJSIP/1508-00002bab", "CHANNEL(accountcode)=1508") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:22] ExecIf("PJSIP/1508-00002bab", "0?SIPAddHeader(Privacy:id)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:23] Gosub("PJSIP/1508-00002bab", "sub_record_call,start,1(1508,outgoing,606256088)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:1] NoOp("PJSIP/1508-00002bab", "sub_record_call: arg1: 1508 arg2: outgoing arg3: 606256088") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:2] GotoIf("PJSIP/1508-00002bab", "0?volver") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:3] Set("PJSIP/1508-00002bab", "RECORDPREF=000") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:4] Verbose("PJSIP/1508-00002bab", "RECORDPREF 000 inc :0 out: 0 int: 0 ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] app_verbose.c: RECORDPREF 000 inc :0 out: 0 int: 0 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:5] GotoIf("PJSIP/1508-00002bab", "0?inc") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:6] GotoIf("PJSIP/1508-00002bab", "0?int") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:7] GotoIf("PJSIP/1508-00002bab", "1?out") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx_builtins.c: Goto (sub_record_call,start,12) [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:12] GotoIf("PJSIP/1508-00002bab", "0?graba") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_record_call:13] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_pstn_task:24] Gosub("PJSIP/1508-00002bab", "sub_call_pstn,start,1(606256088,13,7)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:1] NoOp("PJSIP/1508-00002bab", "subrutina de llamar a pstn ARG1:Destino 606256088 | ARG2: es ID destino 1 13 | ARG3: es ID destino 2 7 ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:2] Gosub("PJSIP/1508-00002bab", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/1508-00002bab", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/1508-00002bab", "disabled") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:3] Gosub("PJSIP/1508-00002bab", "sub_geolocation_callee,start,1(606256088)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_geolocation_callee:1] Verbose("PJSIP/1508-00002bab", "tratando de geolocalizar 606256088") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] app_verbose.c: tratando de geolocalizar 606256088 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_geolocation_callee:2] GotoIf("PJSIP/1508-00002bab", "0?int") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_geolocation_callee:3] Set("PJSIP/1508-00002bab", "CDR(dst_geocountry)=España") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_geolocation_callee:4] ExecIf("PJSIP/1508-00002bab", "1?Return()") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:4] Set("PJSIP/1508-00002bab", "__DESTINATION=606256088") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:5] ExecIf("PJSIP/1508-00002bab", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:6] ExecIf("PJSIP/1508-00002bab", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:7] Set("PJSIP/1508-00002bab", "CALLERID(all)=935678888") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:8] Set("PJSIP/1508-00002bab", "CDR(src_callerid)=") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:9] ExecIf("PJSIP/1508-00002bab", "1?SIPAddHeader("X-Exten:1508")") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:10] Set("PJSIP/1508-00002bab", "ARRAY(PEER,CALLERID_OPTION)=colegi-metges,,") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:11] NoOp("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:12] Gosub("PJSIP/1508-00002bab", "custom_sub_callerid,start,1(606256088)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@custom_sub_callerid:1] NoOp("PJSIP/1508-00002bab", "custom_sub_callerid llamada a:606256088 PEER:colegi-metges PREFIX_OUTGOING:") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@custom_sub_callerid:2] NoOp("PJSIP/1508-00002bab", "PREFIX ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@custom_sub_callerid:3] ExecIf("PJSIP/1508-00002bab", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@custom_sub_callerid:4] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:13] SIPRemoveHeader("PJSIP/1508-00002bab", "Alert-info:") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:14] NoOp("PJSIP/1508-00002bab", " DESTINATION 606256088 | PEER colegi-metges ") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:15] Set("PJSIP/1508-00002bab", "ARRAY(SIP_MODE)=PJSIP") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_call_pstn:16] Dial("PJSIP/1508-00002bab", "SIP/colegi-metges/606256088,300,T") in new stack [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] netsock2.c: Using SIP RTP CoS mark 5 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] app_dial.c: Called SIP/colegi-metges/606256088 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] app_dial.c: SIP/colegi-metges-00000842 is ringing [2022-05-26 18:32:13] VERBOSE[1085][C-00001026] res_rtp_asterisk.c: 0x14b1f437bb00 -- Strict RTP learning after remote address set to: 212.36.72.3:42284 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] app_dial.c: SIP/colegi-metges-00000842 is making progress passing it to PJSIP/1508-00002bab [2022-05-26 18:32:13] VERBOSE[11502] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP learning after remote address set to: 172.25.150.78:28712 [2022-05-26 18:32:13] VERBOSE[5694][C-00001026] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP switching to RTP target address 172.25.150.78:28712 as source [2022-05-26 18:32:14] VERBOSE[5694][C-00001026] app_dial.c: SIP/colegi-metges-00000842 is making progress passing it to PJSIP/1508-00002bab [2022-05-26 18:32:14] VERBOSE[5694][C-00001026] res_rtp_asterisk.c: 0x14b1f437bb00 -- Strict RTP switching to RTP target address 212.36.72.3:42284 as source [2022-05-26 18:32:14] VERBOSE[5694][C-00001026] app_dial.c: SIP/colegi-metges-00000842 is making progress passing it to PJSIP/1508-00002bab [2022-05-26 18:32:18] VERBOSE[5694][C-00001026] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP learning complete - Locking on source address 172.25.150.78:28712 [2022-05-26 18:32:18] VERBOSE[5694][C-00001026] res_rtp_asterisk.c: 0x14b1f437bb00 -- Strict RTP learning complete - Locking on source address 212.36.72.3:42284 [2022-05-26 18:32:23] VERBOSE[5702] manager.c: HTTP Manager 'zabbix' logged on from 172.30.1.10 [2022-05-26 18:32:23] VERBOSE[5703] manager.c: HTTP Manager 'zabbix' logged off from 172.30.1.10 [2022-05-26 18:32:24] VERBOSE[5704] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:32:25] VERBOSE[5707] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:32:35] VERBOSE[5694][C-00001026] app_dial.c: SIP/colegi-metges-00000842 answered PJSIP/1508-00002bab [2022-05-26 18:32:35] VERBOSE[5713][C-00001026] bridge_channel.c: Channel SIP/colegi-metges-00000842 joined 'simple_bridge' basic-bridge [2022-05-26 18:32:35] VERBOSE[5694][C-00001026] bridge_channel.c: Channel PJSIP/1508-00002bab joined 'simple_bridge' basic-bridge [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] bridge_channel.c: Channel PJSIP/1508-00002bab left 'simple_bridge' basic-bridge [2022-05-26 18:32:39] VERBOSE[5713][C-00001026] bridge_channel.c: Channel SIP/colegi-metges-00000842 left 'simple_bridge' basic-bridge [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Spawn extension (sub_call_pstn, start, 16) exited non-zero on 'PJSIP/1508-00002bab' [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [h@sub_call_pstn:1] NoOp("PJSIP/1508-00002bab", "Colgamos llamada 606256088") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [h@sub_call_pstn:2] Gosub("PJSIP/1508-00002bab", "sub_notify_API,start,1(sub_call_pstn,OUTGOING,OUT)") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/1508-00002bab", "ARG1 CONTEXT:sub_call_pstn | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:OUTGOING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: OUT") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/1508-00002bab", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [h@sub_call_pstn:3] Gosub("PJSIP/1508-00002bab", "sub_alert_message_pstn,start,1())") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:1] NoOp("PJSIP/1508-00002bab", "935678888 |ARG1: )| CONTEXT: sub_alert_message_pstn | ") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:2] ExecIf("PJSIP/1508-00002bab", "0?Return") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:3] ExecIf("PJSIP/1508-00002bab", "0??Congestion(2)") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:4] ExecIf("PJSIP/1508-00002bab", "0?Busy(2)") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:5] ExecIf("PJSIP/1508-00002bab", "0?Congestion(2)") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:6] ExecIf("PJSIP/1508-00002bab", "0?Congestion(2)") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [start@sub_alert_message_pstn:7] Return("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Executing [h@sub_call_pstn:4] Hangup("PJSIP/1508-00002bab", "") in new stack [2022-05-26 18:32:39] VERBOSE[5694][C-00001026] pbx.c: Spawn extension (sub_call_pstn, h, 4) exited non-zero on 'PJSIP/1508-00002bab' [2022-05-26 18:32:58] VERBOSE[1085][C-00001027] netsock2.c: Using SIP RTP CoS mark 5 [2022-05-26 18:32:58] VERBOSE[1085][C-00001027] res_rtp_asterisk.c: 0x14b1c00e1160 -- Strict RTP learning after remote address set to: 212.36.72.3:43320 [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:1] NoOp("SIP/colegi-metges-00000843", "606256088 |DID: 935678888| CONTEXT: from-pstn") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:2] Set("SIP/colegi-metges-00000843", "CDR(type)=incoming") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:3] ExecIf("SIP/colegi-metges-00000843", "0?Set(CDR(src_callerid)=606256088):Set(CDR(src_callerid)=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:4] Set("SIP/colegi-metges-00000843", "CDR(dst_dialed)=935678888") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:5] Set("SIP/colegi-metges-00000843", "CHANNEL(accountcode)=935678888") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:6] ExecIf("SIP/colegi-metges-00000843", "0?Set(__src_number=606256088):Set(__src_number=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:7] ExecIf("SIP/colegi-metges-00000843", "0?NoOP(No modificamos CALLERID(num)):Set(CALLERID(num)=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn:8] Goto("SIP/colegi-metges-00000843", "from-pstn-custom,935678888,1") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx_builtins.c: Goto (from-pstn-custom,935678888,1) [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:1] NoOp("SIP/colegi-metges-00000843", "0606256088 |DID: 935678888| CONTEXT: from-pstn-custom CUSTOM DDI") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:2] Set("SIP/colegi-metges-00000843", "__clid_number=0606256088") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:3] Set("SIP/colegi-metges-00000843", "CALLERID(num)=0606256088") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:4] Set("SIP/colegi-metges-00000843", "ARRAY(QNAME,OPC_COLA,QTIMEOUT)=GRUP_OPERADORES,tI,300") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:5] ExecIf("SIP/colegi-metges-00000843", "0?Set(__QCALLERIDNAME=GRUP_OPERADORES-):Set(__QCALLERIDNAME=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:6] ExecIf("SIP/colegi-metges-00000843", "1?SET(__QCALLERID=GRUP_OPERADORES)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:7] ExecIf("SIP/colegi-metges-00000843", "1?SET(__QCALLERIDNUM=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:8] ExecIf("SIP/colegi-metges-00000843", "1?SET(CALLERID(name)=GRUP_OPERADORES)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:9] ExecIf("SIP/colegi-metges-00000843", "1?SET(CALLERID(num)=0606256088)") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:10] Progress("SIP/colegi-metges-00000843", "") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:11] Ringing("SIP/colegi-metges-00000843", "") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:12] Wait("SIP/colegi-metges-00000843", "1") in new stack [2022-05-26 18:32:58] VERBOSE[5724][C-00001027] res_rtp_asterisk.c: 0x14b1c00e1160 -- Strict RTP switching to RTP target address 212.36.72.3:43320 as source [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:13] Answer("SIP/colegi-metges-00000843", "") in new stack [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:14] Set("SIP/colegi-metges-00000843", "__QNAME=GRUP_OPERADORES") in new stack [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] pbx.c: Executing [935678888@from-pstn-custom:15] Queue("SIP/colegi-metges-00000843", "GRUP_OPERADORES,tI,,,300,,,") in new stack [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/colegi-metges-00000843' [2022-05-26 18:32:59] ERROR[18883] res_pjsip.c: Endpoint '1000': Could not create dialog to invalid URI '1000'. Is endpoint registered and reachable? [2022-05-26 18:32:59] ERROR[18883] chan_pjsip.c: Failed to create outgoing session to endpoint '1000' [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Called Local/1003@to-user/n [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:1] NoOp("Local/1003@to-user-00000abb;2", "to-user de:0606256088 a:1003 ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:2] Gosub("Local/1003@to-user-00000abb;2", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("Local/1003@to-user-00000abb;2", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("Local/1003@to-user-00000abb;2", "disabled") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_VARIABLES:3] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:3] Set("Local/1003@to-user-00000abb;2", "__OriginContext=to-user") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:4] Set("Local/1003@to-user-00000abb;2", "__TRANSFER_CONTEXT=to-user") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:5] Gosub("Local/1003@to-user-00000abb;2", "define_location,extension,1(1003)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [extension@define_location:1] NoOp("Local/1003@to-user-00000abb;2", "Definimos a que sede corresponde") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [extension@define_location:2] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:6] SIPRemoveHeader("Local/1003@to-user-00000abb;2", "X-Location:") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:7] Gosub("Local/1003@to-user-00000abb;2", "sub_determine_scenario,start,1(internal,extension,1003)") in new stack [2022-05-26 18:32:59] ERROR[11502] res_pjsip.c: Endpoint '1001': Could not create dialog to invalid URI '1001'. Is endpoint registered and reachable? [2022-05-26 18:32:59] ERROR[11502] chan_pjsip.c: Failed to create outgoing session to endpoint '1001' [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:1] NoOp("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:2] SIPAddHeader("Local/1003@to-user-00000abb;2", ""X-CID:"") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:3] GotoIf("Local/1003@to-user-00000abb;2", "0?pbxcfu") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:4] GotoIf("Local/1003@to-user-00000abb;2", "0?sipbxfer") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:5] GotoIf("Local/1003@to-user-00000abb;2", "0?pbxatxfer") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:6] GotoIf("Local/1003@to-user-00000abb;2", "0?pbxbxfer") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:7] GotoIf("Local/1003@to-user-00000abb;2", "0?sipcfu") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:8] GotoIf("Local/1003@to-user-00000abb;2", "0?disacall") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:9] Set("Local/1003@to-user-00000abb;2", "__SRCEXTEN=0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:10] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:11] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:12] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(__SRCEXTEN=2990)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:13] Goto("Local/1003@to-user-00000abb;2", "normal") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_determine_scenario,start,46) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:46] ExecIf("Local/1003@to-user-00000abb;2", "1?Set(CALLERID(num)=0606256088)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:47] ExecIf("Local/1003@to-user-00000abb;2", "1?Set(CALLERID(num)=0606256088)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:48] Goto("Local/1003@to-user-00000abb;2", "cdr") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_determine_scenario,start,49) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:49] NoOp("Local/1003@to-user-00000abb;2", "0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Called PJSIP/1004 [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] func_odbc.c: Found no rows [SELECT id,seatId,departamentId FROM user WHERE extension = '0606256088'] [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Connected line update to SIP/colegi-metges-00000843 prevented. [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:50] Set("Local/1003@to-user-00000abb;2", "ARRAY(id,seatId,departamentId)=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:51] Set("Local/1003@to-user-00000abb;2", "CDR(seatId)=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:52] Set("Local/1003@to-user-00000abb;2", "CDR(departamentId)=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:53] Set("Local/1003@to-user-00000abb;2", "EXTEN_Id=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:54] Set("Local/1003@to-user-00000abb;2", "CDR(type)=internal") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:55] Set("Local/1003@to-user-00000abb;2", "CDR(subtype)=extension") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:56] Set("Local/1003@to-user-00000abb;2", "CDR(src_extension)=0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:57] Set("Local/1003@to-user-00000abb;2", "CDR(src_callerid)=0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:58] Set("Local/1003@to-user-00000abb;2", "CDR(dst_dialed)=1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_determine_scenario:59] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:8] Gosub("Local/1003@to-user-00000abb;2", "sub_record_call,start,1(0606256088,internal,1003)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:1] NoOp("Local/1003@to-user-00000abb;2", "sub_record_call: arg1: 0606256088 arg2: internal arg3: 1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:2] GotoIf("Local/1003@to-user-00000abb;2", "0?volver") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] func_odbc.c: Found no rows [SELECT record from user where extension='0606256088'] [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:3] Set("Local/1003@to-user-00000abb;2", "RECORDPREF=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:4] Verbose("Local/1003@to-user-00000abb;2", "RECORDPREF inc : out: int: ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_verbose.c: RECORDPREF inc : out: int: [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:5] GotoIf("Local/1003@to-user-00000abb;2", "0?inc") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:6] GotoIf("Local/1003@to-user-00000abb;2", "1?int") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_record_call,start,14) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:14] GotoIf("Local/1003@to-user-00000abb;2", "0?graba") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:15] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:9] Gosub("Local/1003@to-user-00000abb;2", "sub_record_call,start,1(1003,internal,0606256088)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:1] NoOp("Local/1003@to-user-00000abb;2", "sub_record_call: arg1: 1003 arg2: internal arg3: 0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:2] GotoIf("Local/1003@to-user-00000abb;2", "0?volver") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:3] Set("Local/1003@to-user-00000abb;2", "RECORDPREF=000") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:4] Verbose("Local/1003@to-user-00000abb;2", "RECORDPREF 000 inc :0 out: 0 int: 0 ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_verbose.c: RECORDPREF 000 inc :0 out: 0 int: 0 [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:5] GotoIf("Local/1003@to-user-00000abb;2", "0?inc") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:6] GotoIf("Local/1003@to-user-00000abb;2", "1?int") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_record_call,start,14) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:14] GotoIf("Local/1003@to-user-00000abb;2", "0?graba") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_record_call:15] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:10] ExecIf("Local/1003@to-user-00000abb;2", "1?Set(PJSIP_HEADER(remove,Alert-Info)=):SIPRemoveHeader(Alert-info:)") in new stack [2022-05-26 18:32:59] ERROR[5822][C-00001027] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@to-user:11] Gosub("Local/1003@to-user-00000abb;2", "sub_call_user,1003,1(1003)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:1] NoOp("Local/1003@to-user-00000abb;2", "Entrando en busqueda del usuario ARG1 1003| EXTEN 1003| DIALTIMEOUT 60 | from CALLERID(num) : 0606256088 | CALLERID(name) GRUP_OPERADORES | QNAME GRUP_OPERADORES | QCALLERIDNAME 0606256088 | QCALLERID GRUP_OPERADORES ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:2] Set("Local/1003@to-user-00000abb;2", "ARG1=1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:3] Set("Local/1003@to-user-00000abb;2", "__DYNAMIC_FEATURES=medicorase-lopd#testfeature") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:4] Set("Local/1003@to-user-00000abb;2", "__HEREDAUSER=1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:5] Set("Local/1003@to-user-00000abb;2", "__HEREDACALLERID=0606256088") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:6] Verbose("Local/1003@to-user-00000abb;2", "LEN 10 | 0606256088 ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_verbose.c: LEN 10 | 0606256088 [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:7] GotoIf("Local/1003@to-user-00000abb;2", "0?externalcall_cid:internal_cid") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_call_user,1003,10) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:10] Gosub("Local/1003@to-user-00000abb;2", "sub_musiconhold,start,1") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] func_odbc.c: Found no rows [SELECT name from moh where active = '1'] [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold:1] Set("Local/1003@to-user-00000abb;2", "MOH=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold:2] Gosub("Local/1003@to-user-00000abb;2", "sub_musiconhold_custom,start,1()") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold_custom:1] NoOp("Local/1003@to-user-00000abb;2", "Musiconhold custom - ARG1 = ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold_custom:2] GotoIf("Local/1003@to-user-00000abb;2", "0?existe:noexiste") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_musiconhold_custom,start,4) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold_custom:4] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold:3] GotoIf("Local/1003@to-user-00000abb;2", "0?existe:noexiste") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx_builtins.c: Goto (sub_musiconhold,start,6) [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold:6] Set("Local/1003@to-user-00000abb;2", "CHANNEL(musicclass)=default") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [start@sub_musiconhold:7] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:11] Set("Local/1003@to-user-00000abb;2", "DIALOPT=TtHhcgb(outbound_handler^set_handler^1(Local/1003@to-user-00000abb;2))") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:12] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(DIALOPT=TtHhcgb(outbound_handler^set_handler^1(Local/1003@to-user-00000abb;2)))") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:13] Set("Local/1003@to-user-00000abb;2", "CDR(dst_extension)=1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:14] Set("Local/1003@to-user-00000abb;2", "ARRAY(CFU,CFNA,CWAIT,RING,VM)=,,0,18,0") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:15] NoOp("Local/1003@to-user-00000abb;2", "Valor de CFU CFNA CWAIT RING 18 VM 0 IGNORE_CF ") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] func_odbc.c: Found no rows [SELECT name FROM user WHERE extension = '0606256088'] [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:16] Set("Local/1003@to-user-00000abb;2", "ARRAY(NAME)=") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:17] ExecIf("Local/1003@to-user-00000abb;2", "0?SET(CALLERID(name)=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:18] NoOp("Local/1003@to-user-00000abb;2", "Valor de CFU: CFNA: | RING: '18'| VM: 0 FROM:GRUP_OPERADORES DIALOPT: TtHhcgb(outbound_handler^set_handler^1(Local/1003@to-user-00000abb;2)) SRCBXFER: BXFER_COUNT: 0") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:19] ExecIf("Local/1003@to-user-00000abb;2", "0?SET(DIALTIMEOUT=30)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:20] ExecIf("Local/1003@to-user-00000abb;2", "0?NoOp(no RING or NULL)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:21] ExecIf("Local/1003@to-user-00000abb;2", "1?SET(DIALTIMEOUT=18):SET(DIALTIMEOUT=60)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:22] ExecIf("Local/1003@to-user-00000abb;2", "0?SET(DIALTIMEOUT=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:23] GotoIf("Local/1003@to-user-00000abb;2", "0?bfxerloophangup,1") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:24] ExecIf("Local/1003@to-user-00000abb;2", "0?GoSub(sub_cw,start,1(1003,NOT_INUSE))") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:25] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(ARRAY(CFU_LOOP,CFNA_2,RING_2,VM_2)=,,0,18,0)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:26] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(ARRAY(CFU_LOOP,CFNA_2,RING_2,VM_2)=,,0,18,0)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:27] ExecIf("Local/1003@to-user-00000abb;2", "0?SET(IGNORE_CF=1)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:28] ExecIf("Local/1003@to-user-00000abb;2", "0?Goto(llamar)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:29] ExecIf("Local/1003@to-user-00000abb;2", "0?Goto(llamar)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:30] ExecIf("Local/1003@to-user-00000abb;2", "0?Goto(llamar)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:31] GotoIf("Local/1003@to-user-00000abb;2", "0?cfu,1") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:32] ExecIf("Local/1003@to-user-00000abb;2", "1?SET(CALLERID(name)=GRUP_OPERADORES)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:33] ExecIf("Local/1003@to-user-00000abb;2", "1?SET(CALLERID(num)=0606256088)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:34] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(DIAL_SIP_EXT=&SIP/1003):Set(DIAL_SIP_EXT=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:35] ExecIf("Local/1003@to-user-00000abb;2", "1?Set(DIAL_SIP_EXT=)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:36] Gosub("Local/1003@to-user-00000abb;2", "custom_sub_call_user_before,1003,1(1003,1003)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_before:1] NoOp("Local/1003@to-user-00000abb;2", "custom custom_sub_call_user_before 1003 1003") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_before:2] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:37] ExecIf("Local/1003@to-user-00000abb;2", "0?Dial(&,18,TtHhcgb(outbound_handler^set_handler^1(Local/1003@to-user-00000abb;2))):Dial(PJSIP/1003/sip:1003@172.25.100.153:30371&,18,TtHhcgb(outbound_handler^set_handler^1(Local/1003@to-user-00000abb;2)))") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_stack.c: PJSIP/1003-00002bad Internal Gosub(outbound_handler,set_handler,1(Local/1003@to-user-00000abb;2)) start [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [set_handler@outbound_handler:1] NoOp("PJSIP/1003-00002bad", "Local/1003@to-user-00000abb;2") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [set_handler@outbound_handler:2] Set("PJSIP/1003-00002bad", "CHANNEL(hangup_handler_push)=outbound_handler,outbound_handler,1(Local/1003@to-user-00000abb;2)") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] pbx.c: Executing [set_handler@outbound_handler:3] Return("PJSIP/1003-00002bad", "") in new stack [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_stack.c: Spawn extension (from-users, 1003, 1) exited non-zero on 'PJSIP/1003-00002bad' [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_stack.c: PJSIP/1003-00002bad Internal Gosub(outbound_handler,set_handler,1(Local/1003@to-user-00000abb;2)) complete GOSUB_RETVAL= [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_dial.c: Called PJSIP/1003/sip:1003@172.25.100.153:30371 [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Connected line update to SIP/colegi-metges-00000843 prevented. [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Nobody picked up in 0 ms [2022-05-26 18:32:59] VERBOSE[5822][C-00001027] app_dial.c: PJSIP/1003-00002bad is ringing [2022-05-26 18:32:59] VERBOSE[5724][C-00001027] app_queue.c: Local/1003@to-user-00000abb;1 is ringing [2022-05-26 18:33:03] VERBOSE[5724][C-00001027] res_rtp_asterisk.c: 0x14b1c00e1160 -- Strict RTP learning complete - Locking on source address 212.36.72.3:43320 [2022-05-26 18:33:03] VERBOSE[17268] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP learning after remote address set to: 172.25.100.153:57220 [2022-05-26 18:33:03] VERBOSE[5822][C-00001027] app_dial.c: PJSIP/1003-00002bad answered Local/1003@to-user-00000abb;2 [2022-05-26 18:33:03] VERBOSE[5724][C-00001027] app_queue.c: Local/1003@to-user-00000abb;1 answered SIP/colegi-metges-00000843 [2022-05-26 18:33:03] VERBOSE[5724][C-00001027] res_musiconhold.c: Stopped music on hold on SIP/colegi-metges-00000843 [2022-05-26 18:33:03] VERBOSE[5851][C-00001027] bridge_channel.c: Channel PJSIP/1003-00002bad joined 'simple_bridge' basic-bridge <0a7e37d4-d08e-4f5b-8398-70f3aa59909c> [2022-05-26 18:33:03] VERBOSE[5822][C-00001027] bridge_channel.c: Channel Local/1003@to-user-00000abb;2 joined 'simple_bridge' basic-bridge <0a7e37d4-d08e-4f5b-8398-70f3aa59909c> [2022-05-26 18:33:03] VERBOSE[5854][C-00001027] bridge_channel.c: Channel Local/1003@to-user-00000abb;1 joined 'simple_bridge' basic-bridge <321be839-73ba-4f4c-a38a-212a02deb11a> [2022-05-26 18:33:03] VERBOSE[5724][C-00001027] bridge_channel.c: Channel SIP/colegi-metges-00000843 joined 'simple_bridge' basic-bridge <321be839-73ba-4f4c-a38a-212a02deb11a> [2022-05-26 18:33:04] VERBOSE[5851][C-00001027] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP switching to RTP target address 172.25.100.153:57220 as source [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [0645209716@from-users:1] Gosub("PJSIP/2452-00002bae", "sub_pstn_task,start,1(645209716,movil)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:1] NoOp("PJSIP/2452-00002bae", "movil,645209716,") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:2] Gosub("PJSIP/2452-00002bae", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/2452-00002bae", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/2452-00002bae", "disabled") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:3] Gosub("PJSIP/2452-00002bae", "sub_determine_scenario,start,1(outgoing,movil,645209716)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:1] NoOp("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:2] SIPAddHeader("PJSIP/2452-00002bae", ""X-CID:"") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:3] GotoIf("PJSIP/2452-00002bae", "0?pbxcfu") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:4] GotoIf("PJSIP/2452-00002bae", "0?sipbxfer") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:5] GotoIf("PJSIP/2452-00002bae", "0?pbxatxfer") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:6] GotoIf("PJSIP/2452-00002bae", "0?pbxbxfer") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:7] GotoIf("PJSIP/2452-00002bae", "0?sipcfu") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:8] GotoIf("PJSIP/2452-00002bae", "0?disacall") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:9] Set("PJSIP/2452-00002bae", "__SRCEXTEN=2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:10] ExecIf("PJSIP/2452-00002bae", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:11] ExecIf("PJSIP/2452-00002bae", "0?Set(__SRCEXTEN=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:12] ExecIf("PJSIP/2452-00002bae", "0?Set(__SRCEXTEN=2990)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:13] Goto("PJSIP/2452-00002bae", "normal") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx_builtins.c: Goto (sub_determine_scenario,start,46) [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:46] ExecIf("PJSIP/2452-00002bae", "0?Set(CALLERID(num)=2452)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:47] ExecIf("PJSIP/2452-00002bae", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:48] Goto("PJSIP/2452-00002bae", "cdr") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx_builtins.c: Goto (sub_determine_scenario,start,49) [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:49] NoOp("PJSIP/2452-00002bae", "2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:50] Set("PJSIP/2452-00002bae", "ARRAY(id,seatId,departamentId)=29352,6,67") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:51] Set("PJSIP/2452-00002bae", "CDR(seatId)=6") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:52] Set("PJSIP/2452-00002bae", "CDR(departamentId)=67") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:53] Set("PJSIP/2452-00002bae", "EXTEN_Id=29352") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:54] Set("PJSIP/2452-00002bae", "CDR(type)=outgoing") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:55] Set("PJSIP/2452-00002bae", "CDR(subtype)=movil") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:56] Set("PJSIP/2452-00002bae", "CDR(src_extension)=2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:57] Set("PJSIP/2452-00002bae", "CDR(src_callerid)=2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:58] Set("PJSIP/2452-00002bae", "CDR(dst_dialed)=645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_determine_scenario:59] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] func_odbc.c: Found no rows [SELECT country,detail,blackList from prefijos_internacional where '5209716' like concat(prefijo,'%') order by prefijo desc limit 1] [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:4] ExecIf("PJSIP/2452-00002bae", "0?Set(ARRAY(geo_country,geo_zone,geo_blackList)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:5] GotoIf("PJSIP/2452-00002bae", "0?blacklist") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:6] NoOp("PJSIP/2452-00002bae", "movil,645209716,2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:7] Set("PJSIP/2452-00002bae", "__ddi_number=645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:8] Gosub("PJSIP/2452-00002bae", "define_ddi_envioroment,s,1") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:1] NoOp("PJSIP/2452-00002bae", "Recogiendo variables para el numero 645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] func_odbc.c: Found no rows [SELECT id,seatId,departamentId,timeOut,custom,description FROM ddi WHERE ddi = '645209716'] [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:2] Set("PJSIP/2452-00002bae", "ARRAY(ddi_Id,seatId,departamentId,ddi_timeOut,ddi_custom,ddi_description)=") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:3] Set("PJSIP/2452-00002bae", "ARRAY(CDR(seatId),CDR(departamentId)=,)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:4] AGI("PJSIP/2452-00002bae", "processing.php,") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/processing.php [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] res_agi.c: processing.php,: ID de Timegroup a usar [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] res_agi.c: AGI Script processing.php completed, returning 0 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:5] Gosub("PJSIP/2452-00002bae", "sub_notify_API,start,1(define_ddi_envioroment,INCOMING,IN)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_notify_API:1] NoOp("PJSIP/2452-00002bae", "ARG1 CONTEXT:define_ddi_envioroment | ARG2 DEST_TYPE QUEUE,INCOMING,OUTGOING:INCOMING| ARG3 IN,OUT,Q_IN,Q_AGENT,Q_OUT: IN") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_notify_API:2] ExecIf("PJSIP/2452-00002bae", "0?NoOP(ok execute sub_notify_API):Return") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:6] NoOp("PJSIP/2452-00002bae", "TimeGroupID ddi_timeGroupId: | ddi_description: ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [s@define_ddi_envioroment:7] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:9] ExecIf("PJSIP/2452-00002bae", "0?Set(noPrefix=1)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:10] GotoIf("PJSIP/2452-00002bae", "0?from-pstn,645209716,1") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:11] NoOp("PJSIP/2452-00002bae", "Llamada a:645209716 desde:2452 arg2 movil DISACALL: NUMCORTA ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:12] Set("PJSIP/2452-00002bae", "ARRAY(category_id,category_OutboundRoutesId,PREFIX_OUTGOING,category_FailOverOutboundRoutesId)=121,13,,7") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] func_odbc.c: Found no rows [SELECT d.id,d.outboundRoutesId,d.callerId,d.failOverOutboundRoutesId FROM categories d LEFT JOIN user u ON (d.categoryGroupId=u.categoryGroupId) WHERE u.extension='' AND '645209716' like concat(prefix,'%') order by prefix desc] [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:13] ExecIf("PJSIP/2452-00002bae", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:14] ExecIf("PJSIP/2452-00002bae", "0?Set(ARRAY(category_id,category_OutboundRoutesId,category_FailOverOutboundRoutesId)=121,13,,7)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:15] NoOp("PJSIP/2452-00002bae", " category_id 121 ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:16] Set("PJSIP/2452-00002bae", "_PREFIX_OUTGOING=") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:17] Set("PJSIP/2452-00002bae", "__TRANSFER_CONTEXT=from-users") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:18] GotoIf("PJSIP/2452-00002bae", "1?conpermiso:sinpermiso") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx_builtins.c: Goto (sub_pstn_task,start,19) [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:19] Set("PJSIP/2452-00002bae", "CALLERID(num)=935678888") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:20] Set("PJSIP/2452-00002bae", "__USERDDI=935678888") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:21] Set("PJSIP/2452-00002bae", "CHANNEL(accountcode)=2452") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:22] ExecIf("PJSIP/2452-00002bae", "0?SIPAddHeader(Privacy:id)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:23] Gosub("PJSIP/2452-00002bae", "sub_record_call,start,1(2452,outgoing,645209716)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:1] NoOp("PJSIP/2452-00002bae", "sub_record_call: arg1: 2452 arg2: outgoing arg3: 645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:2] GotoIf("PJSIP/2452-00002bae", "0?volver") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:3] Set("PJSIP/2452-00002bae", "RECORDPREF=000") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:4] Verbose("PJSIP/2452-00002bae", "RECORDPREF 000 inc :0 out: 0 int: 0 ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] app_verbose.c: RECORDPREF 000 inc :0 out: 0 int: 0 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:5] GotoIf("PJSIP/2452-00002bae", "0?inc") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:6] GotoIf("PJSIP/2452-00002bae", "0?int") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:7] GotoIf("PJSIP/2452-00002bae", "1?out") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx_builtins.c: Goto (sub_record_call,start,12) [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:12] GotoIf("PJSIP/2452-00002bae", "0?graba") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_record_call:13] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_pstn_task:24] Gosub("PJSIP/2452-00002bae", "sub_call_pstn,start,1(645209716,13,7)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:1] NoOp("PJSIP/2452-00002bae", "subrutina de llamar a pstn ARG1:Destino 645209716 | ARG2: es ID destino 1 13 | ARG3: es ID destino 2 7 ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:2] Gosub("PJSIP/2452-00002bae", "sub_VARIABLES,start,1") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:1] NoOp("PJSIP/2452-00002bae", "1,Recupera variables de pbx.status") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:2] NoOp("PJSIP/2452-00002bae", "disabled") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_VARIABLES:3] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:3] Gosub("PJSIP/2452-00002bae", "sub_geolocation_callee,start,1(645209716)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_geolocation_callee:1] Verbose("PJSIP/2452-00002bae", "tratando de geolocalizar 645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] app_verbose.c: tratando de geolocalizar 645209716 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_geolocation_callee:2] GotoIf("PJSIP/2452-00002bae", "0?int") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_geolocation_callee:3] Set("PJSIP/2452-00002bae", "CDR(dst_geocountry)=España") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_geolocation_callee:4] ExecIf("PJSIP/2452-00002bae", "1?Return()") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:4] Set("PJSIP/2452-00002bae", "__DESTINATION=645209716") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:5] ExecIf("PJSIP/2452-00002bae", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:6] ExecIf("PJSIP/2452-00002bae", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:7] Set("PJSIP/2452-00002bae", "CALLERID(all)=935678888") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:8] Set("PJSIP/2452-00002bae", "CDR(src_callerid)=") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:9] ExecIf("PJSIP/2452-00002bae", "1?SIPAddHeader("X-Exten:2452")") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:10] Set("PJSIP/2452-00002bae", "ARRAY(PEER,CALLERID_OPTION)=colegi-metges,,") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:11] NoOp("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:12] Gosub("PJSIP/2452-00002bae", "custom_sub_callerid,start,1(645209716)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@custom_sub_callerid:1] NoOp("PJSIP/2452-00002bae", "custom_sub_callerid llamada a:645209716 PEER:colegi-metges PREFIX_OUTGOING:") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@custom_sub_callerid:2] NoOp("PJSIP/2452-00002bae", "PREFIX ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@custom_sub_callerid:3] ExecIf("PJSIP/2452-00002bae", "0?Set(CALLERID(num)=)") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@custom_sub_callerid:4] Return("PJSIP/2452-00002bae", "") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:13] SIPRemoveHeader("PJSIP/2452-00002bae", "Alert-info:") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:14] NoOp("PJSIP/2452-00002bae", " DESTINATION 645209716 | PEER colegi-metges ") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:15] Set("PJSIP/2452-00002bae", "ARRAY(SIP_MODE)=PJSIP") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] pbx.c: Executing [start@sub_call_pstn:16] Dial("PJSIP/2452-00002bae", "SIP/colegi-metges/645209716,300,T") in new stack [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] netsock2.c: Using SIP RTP CoS mark 5 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] app_dial.c: Called SIP/colegi-metges/645209716 [2022-05-26 18:33:04] VERBOSE[1085][C-00001028] res_rtp_asterisk.c: 0x14b21c034990 -- Strict RTP learning after remote address set to: 212.36.72.3:43486 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] app_dial.c: SIP/colegi-metges-00000844 is making progress passing it to PJSIP/2452-00002bae [2022-05-26 18:33:04] VERBOSE[10013] res_rtp_asterisk.c: 0x14b1ac35d250 -- Strict RTP learning after remote address set to: 172.25.90.91:40754 [2022-05-26 18:33:04] VERBOSE[5856][C-00001028] res_rtp_asterisk.c: 0x14b21c034990 -- Strict RTP switching to RTP target address 212.36.72.3:43486 as source [2022-05-26 18:33:05] VERBOSE[5856][C-00001028] res_rtp_asterisk.c: 0x14b1ac35d250 -- Strict RTP switching to RTP target address 172.25.90.91:40754 as source [2022-05-26 18:33:05] VERBOSE[5856][C-00001028] app_dial.c: SIP/colegi-metges-00000844 is making progress passing it to PJSIP/2452-00002bae [2022-05-26 18:33:05] VERBOSE[5856][C-00001028] app_dial.c: SIP/colegi-metges-00000844 is making progress passing it to PJSIP/2452-00002bae [2022-05-26 18:33:08] VERBOSE[5851][C-00001027] res_rtp_asterisk.c: 0x14b26406f030 -- Strict RTP learning complete - Locking on source address 172.25.100.153:57220 [2022-05-26 18:33:09] VERBOSE[5856][C-00001028] res_rtp_asterisk.c: 0x14b1ac35d250 -- Strict RTP learning complete - Locking on source address 172.25.90.91:40754 [2022-05-26 18:33:09] VERBOSE[5856][C-00001028] res_rtp_asterisk.c: 0x14b21c034990 -- Strict RTP learning complete - Locking on source address 212.36.72.3:43486 [2022-05-26 18:33:12] VERBOSE[5856][C-00001028] app_dial.c: SIP/colegi-metges-00000844 answered PJSIP/2452-00002bae [2022-05-26 18:33:12] VERBOSE[5860][C-00001028] bridge_channel.c: Channel SIP/colegi-metges-00000844 joined 'simple_bridge' basic-bridge [2022-05-26 18:33:12] VERBOSE[5856][C-00001028] bridge_channel.c: Channel PJSIP/2452-00002bae joined 'simple_bridge' basic-bridge [2022-05-26 18:33:23] VERBOSE[5863] manager.c: HTTP Manager 'zabbix' logged on from 172.30.1.10 [2022-05-26 18:33:23] VERBOSE[5864] manager.c: HTTP Manager 'zabbix' logged off from 172.30.1.10 [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] bridge_channel.c: Channel PJSIP/1003-00002bad left 'simple_bridge' basic-bridge <0a7e37d4-d08e-4f5b-8398-70f3aa59909c> [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] bridge_channel.c: Channel Local/1003@to-user-00000abb;2 left 'simple_bridge' basic-bridge <0a7e37d4-d08e-4f5b-8398-70f3aa59909c> [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] app_stack.c: PJSIP/1003-00002bad Internal Gosub(outbound_handler,outbound_handler,1(Local/1003@to-user-00000abb;2)) start [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:38] NoOp("Local/1003@to-user-00000abb;2", "DIALSTATUS: ANSWER") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:1] NoOp("PJSIP/1003-00002bad", "Destination channel Local/1003@to-user-00000abb;2 has hungup") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:39] Gosub("Local/1003@to-user-00000abb;2", "custom_sub_call_user_after,1003,1()") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:2] Set("PJSIP/1003-00002bad", "HANGUPCAUSE_STRING=PJSIP/1003-00002bad") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_after:1] NoOp("Local/1003@to-user-00000abb;2", "custom custom_sub_call_user_after ") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_after:2] ExecIf("Local/1003@to-user-00000abb;2", "0?GoTo(custom_chanunavail_dnd)") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:3] ExecIf("PJSIP/1003-00002bad", "0?Return()") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_after:3] ExecIf("Local/1003@to-user-00000abb;2", "0?GoTo(custom_chanunavail_busy)") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:4] NoOp("PJSIP/1003-00002bad", "Got Channel ID PJSIP/1003-00002bad master Local/1003@to-user-00000abb;2 with Technology Cause Code SIP 200 OK, Asterisk Cause Code Normal Clearing") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@custom_sub_call_user_after:4] Return("Local/1003@to-user-00000abb;2", "") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:5] Set("PJSIP/1003-00002bad", "SHARED(SIPcause,Local/1003@to-user-00000abb;2)=SIP 200 OK") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:40] NoOp("Local/1003@to-user-00000abb;2", "DIALEDPEERNUMBER: 1003/sip:1003@172.25.100.153:30371|SRCEXTEN 0606256088| DIALEDPEERNUMBER 1003/sip:1003@172.25.100.153:30371 | ATTENDEDTRANSFER | HEREDAUSER 1003 ") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:6] Set("PJSIP/1003-00002bad", "SIPMESAGE=SIP 200 OK") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:41] GotoIf("Local/1003@to-user-00000abb;2", "0?cfna,1") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:7] ExecIf("PJSIP/1003-00002bad", "1?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=ANSWER)") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:42] ExecIf("Local/1003@to-user-00000abb;2", "0?Gosub(sub_att_transfer,,1)") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:43] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(BXFER_COUNT=1)") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:44] ExecIf("Local/1003@to-user-00000abb;2", "0?Set(BXFER_COUNT=1)") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Executing [1003@sub_call_user:45] ExecIf("Local/1003@to-user-00000abb;2", "1?Return") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:8] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=FORBIDDEN)") in new stack [2022-05-26 18:33:24] VERBOSE[5822][C-00001027] pbx.c: Auto fallthrough, channel 'Local/1003@to-user-00000abb;2' status is 'ANSWER' [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:9] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=DND)") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:10] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=BUSY)") in new stack [2022-05-26 18:33:24] VERBOSE[5854][C-00001027] bridge_channel.c: Channel Local/1003@to-user-00000abb;1 left 'simple_bridge' basic-bridge <321be839-73ba-4f4c-a38a-212a02deb11a> [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:11] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=NOANSWER)") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:12] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=DND)") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:13] ExecIf("PJSIP/1003-00002bad", "0?Set(SHARED(SIPCAUSE,Local/1003@to-user-00000abb;2)=CONGESTION)") in new stack [2022-05-26 18:33:24] VERBOSE[5724][C-00001027] bridge_channel.c: Channel SIP/colegi-metges-00000843 left 'simple_bridge' basic-bridge <321be839-73ba-4f4c-a38a-212a02deb11a> [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] pbx.c: Executing [outbound_handler@outbound_handler:14] Return("PJSIP/1003-00002bad", "") in new stack [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] app_stack.c: Spawn extension (from-users, , 1) exited non-zero on 'PJSIP/1003-00002bad' [2022-05-26 18:33:24] VERBOSE[5851][C-00001027] app_stack.c: PJSIP/1003-00002bad Internal Gosub(outbound_handler,outbound_handler,1(Local/1003@to-user-00000abb;2)) complete GOSUB_RETVAL= [2022-05-26 18:33:24] VERBOSE[5724][C-00001027] pbx.c: Spawn extension (from-pstn-custom, 935678888, 15) exited non-zero on 'SIP/colegi-metges-00000843' [2022-05-26 18:33:24] VERBOSE[5724][C-00001027] pbx.c: Executing [h@from-pstn-custom:1] NoOp("SIP/colegi-metges-00000843", "Colgamos llamada 'to-cola' from-pstn-custom DIALSTATUS: QUEUECALLS 0") in new stack [2022-05-26 18:33:24] VERBOSE[5724][C-00001027] pbx.c: Executing [h@from-pstn-custom:2] Hangup("SIP/colegi-metges-00000843", "") in new stack [2022-05-26 18:33:24] VERBOSE[5724][C-00001027] pbx.c: Spawn extension (from-pstn-custom, h, 2) exited non-zero on 'SIP/colegi-metges-00000843' [2022-05-26 18:33:24] VERBOSE[5867] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:33:25] VERBOSE[5868] manager.c: Connect attempt from '172.30.1.10' unable to authenticate [2022-05-26 18:33:47] NOTICE[15964] res_pjsip/pjsip_distributor.c: Request 'INVITE' from '' failed for '51.81.27.157:55735' (callid: 1905868380-263848545-171787269) - No matching endpoint found